Aug 21, 2018 02:46:12.003 [0x7fd7a5fff700] INFO - Plex Media Scanner v1.13.5.5291-6fa5e50a8 - ubuntu PC x86_64 - build: linux-ubuntu-x86_64 - GMT -05:00
Aug 21, 2018 02:46:12.004 [0x7fd7a5fff700] INFO - Linux version: 4.9.0-6-amd64 (#1 SMP Debian 4.9.88-1+deb9u1 (2018-05-07)), language: en-US
Aug 21, 2018 02:46:12.004 [0x7fd7a5fff700] INFO - Processor         Intel(R) Atom(TM) CPU  C2750  @ 2.40GHz
Aug 21, 2018 02:46:12.004 [0x7fd7a5fff700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 1753,10882,1914,4446,1904,5198,78,1725 --log-file-suffix  Analysis
Aug 21, 2018 02:46:12.018 [0x7fd7b17d8800] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Aug 21, 2018 02:46:12.340 [0x7fd7b17d8800] DEBUG - Analyzing media parts for item 1753 (The Mom & Pop Store): 3076
Aug 21, 2018 02:46:12.343 [0x7fd7b17d8800] DEBUG - [ID 3076] Media part analysis: /data/TV/Seinfeld/S06/Seinfeld.S06E08.mkv
Aug 21, 2018 02:46:12.343 [0x7fd7b17d8800] DEBUG - [MI] Opening input file: "/data/TV/Seinfeld/S06/Seinfeld.S06E08.mkv"
Aug 21, 2018 02:46:12.343 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Opening '/data/TV/Seinfeld/S06/Seinfeld.S06E08.mkv' for reading
Aug 21, 2018 02:46:12.344 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:12.404 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:12.432 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:12.433 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:12.433 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:12.433 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1325 bytes read:51901 seeks:2 nb_streams:3
Aug 21, 2018 02:46:12.434 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:12.434 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] - palette:
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x161616
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x4b5b06
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0xb7dc12
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:12.436 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:12.437 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:12.437 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:46:12.437 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x0a0a0a
Aug 21, 2018 02:46:12.437 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x545454
Aug 21, 2018 02:46:12.437 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0xdadada
Aug 21, 2018 02:46:12.437 [0x7fd7b17d8800] DEBUG - [FFMPEG] - 
Aug 21, 2018 02:46:12.437 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:12.437 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:12.438 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:12.438 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:12.438 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:12.439 [0x7fd7b17d8800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:12.440 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.441 [0x7fd7b17d8800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:12.442 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.445 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:12.447 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:12.449 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.450 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.451 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:12.453 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:12.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - first_dts 234 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 30.000000 0.000206
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 60.000000 0.000824
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 120.000000 0.003296
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 240.000000 0.013184
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.970030 0.000073
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 59.940060 0.000292
Aug 21, 2018 02:46:12.457 [0x7fd7b17d8800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 41720 bytes read:117437 seeks:2 frames:99
Aug 21, 2018 02:46:12.459 [0x7fd7b17d8800] DEBUG - [MI]  * 8648d66e8e8ef309a72ac470e20e1d093a1a1b84 [mkv h264/ac3] Duration: 1377343ms, 720x480 (1.333333) 29.970fps, bitrate=3124kbps, audio channels=2, optimizedForStreaming=0 ("/data/TV/Seinfeld/S06/Seinfeld.S06E08.mkv")
Aug 21, 2018 02:46:12.460 [0x7fd7b17d8800] DEBUG - [MI] Importing 4 media chapters.
Aug 21, 2018 02:46:12.461 [0x7fd7b17d8800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:12.464 [0x7fd7b17d8800] DEBUG - [MI] Closing input file: "/data/TV/Seinfeld/S06/Seinfeld.S06E08.mkv"
Aug 21, 2018 02:46:12.465 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Statistics: 117437 bytes read, 2 seeks
Aug 21, 2018 02:46:12.468 [0x7fd7b17d8800] DEBUG - Committing media stream changes for part 3076
Aug 21, 2018 02:46:12.483 [0x7fd7b17d8800] DEBUG - Updating media item 3076, size=538000360, metadata_item_id=1753
Aug 21, 2018 02:46:12.484 [0x7fd7b17d8800] DEBUG - Updating part with ID=3076 [/data/TV/Seinfeld/S06/Seinfeld.S06E08.mkv]
Aug 21, 2018 02:46:12.487 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1753&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:12.491 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1753&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:12.492 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1753&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:12.494 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1753&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:12.494 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1753&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:12.496 [0x7fd7b17d8800] DEBUG - Analyzing media parts for item 10882 (Mona Lisa Lost Her Smile): 11699
Aug 21, 2018 02:46:12.496 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1753&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:12.498 [0x7fd7b17d8800] DEBUG - [ID 11699] Media part analysis: /data/Music/David Allan Coe/17 Greatest Hits/01 02 - David Allan Coe - Mona Lisa Lost Her Smile.flac
Aug 21, 2018 02:46:12.498 [0x7fd7b17d8800] DEBUG - [MI] Opening input file: "/data/Music/David Allan Coe/17 Greatest Hits/01 02 - David Allan Coe - Mona Lisa Lost Her Smile.flac"
Aug 21, 2018 02:46:12.499 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Opening '/data/Music/David Allan Coe/17 Greatest Hits/01 02 - David Allan Coe - Mona Lisa Lost Her Smile.flac' for reading
Aug 21, 2018 02:46:12.499 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:12.541 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:12.541 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2336 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:12.542 [0x7fd7b17d8800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:12.542 [0x7fd7b17d8800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 60416 bytes read:65536 seeks:0 frames:1
Aug 21, 2018 02:46:12.543 [0x7fd7b17d8800] DEBUG - [MI]  * a5572965cf3fa71b096aa55f7fb023df97cc4549 [flac /flac] Duration: 220960ms, 0x0 (0.000000) 0.000fps, bitrate=790kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/David Allan Coe/17 Greatest Hits/01 02 - David Allan Coe - Mona Lisa Lost Her Smile.flac")
Aug 21, 2018 02:46:12.544 [0x7fd7b17d8800] DEBUG - [MI] Importing 17 media chapters.
Aug 21, 2018 02:46:12.544 [0x7fd7b17d8800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:12.549 [0x7fd7b17d8800] DEBUG - [MI] Closing input file: "/data/Music/David Allan Coe/17 Greatest Hits/01 02 - David Allan Coe - Mona Lisa Lost Her Smile.flac"
Aug 21, 2018 02:46:12.549 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Aug 21, 2018 02:46:12.551 [0x7fd7b17d8800] DEBUG - Updating media item 11699, size=21827915, metadata_item_id=10882
Aug 21, 2018 02:46:12.552 [0x7fd7b17d8800] DEBUG - Updating part with ID=11699 [/data/Music/David Allan Coe/17 Greatest Hits/01 02 - David Allan Coe - Mona Lisa Lost Her Smile.flac]
Aug 21, 2018 02:46:12.562 [0x7fd7b17d8800] DEBUG - Analyzing media parts for item 1914 (The Squire of Gothos): 3225
Aug 21, 2018 02:46:12.564 [0x7fd7b17d8800] DEBUG - [ID 3225] Media part analysis: /data/TV/Star.Trek/S01/Star.Trek.S01E17.mkv
Aug 21, 2018 02:46:12.564 [0x7fd7b17d8800] DEBUG - [MI] Opening input file: "/data/TV/Star.Trek/S01/Star.Trek.S01E17.mkv"
Aug 21, 2018 02:46:12.565 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Opening '/data/TV/Star.Trek/S01/Star.Trek.S01E17.mkv' for reading
Aug 21, 2018 02:46:12.565 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:12.597 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:12.618 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:12.618 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:12.618 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:12.618 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 962 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:46:12.618 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:12.618 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:12.620 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:12.620 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:12.621 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:12.621 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:12.621 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:12.625 [0x7fd7b17d8800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:12.629 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.632 [0x7fd7b17d8800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:12.637 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.644 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.652 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:12.658 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.665 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.672 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:12.680 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:12.688 [0x7fd7b17d8800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:12.706 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 23.916667 0.007519
Aug 21, 2018 02:46:12.706 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 23.916667 0.007519
Aug 21, 2018 02:46:12.706 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001270
Aug 21, 2018 02:46:12.706 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001270
Aug 21, 2018 02:46:12.706 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 48.000000 0.005081
Aug 21, 2018 02:46:12.706 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 48.000000 0.011011
Aug 21, 2018 02:46:12.706 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:46:12.706 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:46:12.706 [0x7fd7b17d8800] WARN - [FFMPEG] - Could not find codec parameters for stream 2 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Aug 21, 2018 02:46:12.706 [0x7fd7b17d8800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 660460 bytes read:753664 seeks:2 frames:633
Aug 21, 2018 02:46:12.707 [0x7fd7b17d8800] DEBUG - [MI]  * 35f153f7816127c046d51aec10259fd38b2162c6 [mkv h264/dca] Duration: 3041110ms, 1440x1080 (1.333333) 23.976fps, bitrate=4060kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Star.Trek/S01/Star.Trek.S01E17.mkv")
Aug 21, 2018 02:46:12.708 [0x7fd7b17d8800] DEBUG - [MI] Importing 7 media chapters.
Aug 21, 2018 02:46:12.708 [0x7fd7b17d8800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:12.711 [0x7fd7b17d8800] DEBUG - [MI] Closing input file: "/data/TV/Star.Trek/S01/Star.Trek.S01E17.mkv"
Aug 21, 2018 02:46:12.715 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Statistics: 753664 bytes read, 2 seeks
Aug 21, 2018 02:46:12.718 [0x7fd7b17d8800] DEBUG - Committing media stream changes for part 3225
Aug 21, 2018 02:46:12.721 [0x7fd7b17d8800] DEBUG - Updating media item 3225, size=1543695665, metadata_item_id=1914
Aug 21, 2018 02:46:12.721 [0x7fd7b17d8800] DEBUG - Updating part with ID=3225 [/data/TV/Star.Trek/S01/Star.Trek.S01E17.mkv]
Aug 21, 2018 02:46:12.724 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1914&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:12.726 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1914&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:12.727 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1914&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:12.729 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1914&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:12.730 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1914&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:12.732 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1914&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:12.732 [0x7fd7b17d8800] DEBUG - Analyzing media parts for item 4446 (Speak to Me/Breathe): 5847
Aug 21, 2018 02:46:12.735 [0x7fd7b17d8800] DEBUG - [ID 5847] Media part analysis: /data/Music/Pink Floyd/The Dark Side of the Moon/01 01 - Pink Floyd - Speak to Me_Breathe.flac
Aug 21, 2018 02:46:12.735 [0x7fd7b17d8800] DEBUG - [MI] Opening input file: "/data/Music/Pink Floyd/The Dark Side of the Moon/01 01 - Pink Floyd - Speak to Me_Breathe.flac"
Aug 21, 2018 02:46:12.735 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Opening '/data/Music/Pink Floyd/The Dark Side of the Moon/01 01 - Pink Floyd - Speak to Me_Breathe.flac' for reading
Aug 21, 2018 02:46:12.735 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:12.788 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:12.789 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2739 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:12.789 [0x7fd7b17d8800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:12.789 [0x7fd7b17d8800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 4787 bytes read:32768 seeks:0 frames:1
Aug 21, 2018 02:46:12.789 [0x7fd7b17d8800] DEBUG - [MI]  * 4130923c2e4bca959ddbdb3d03af31824fb64243 [flac /flac] Duration: 240507ms, 0x0 (0.000000) 0.000fps, bitrate=711kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Pink Floyd/The Dark Side of the Moon/01 01 - Pink Floyd - Speak to Me_Breathe.flac")
Aug 21, 2018 02:46:12.790 [0x7fd7b17d8800] DEBUG - [MI] Importing 9 media chapters.
Aug 21, 2018 02:46:12.791 [0x7fd7b17d8800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:12.794 [0x7fd7b17d8800] DEBUG - [MI] Closing input file: "/data/Music/Pink Floyd/The Dark Side of the Moon/01 01 - Pink Floyd - Speak to Me_Breathe.flac"
Aug 21, 2018 02:46:12.794 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Aug 21, 2018 02:46:12.797 [0x7fd7b17d8800] DEBUG - Updating media item 5847, size=21401946, metadata_item_id=4446
Aug 21, 2018 02:46:12.798 [0x7fd7b17d8800] DEBUG - Updating part with ID=5847 [/data/Music/Pink Floyd/The Dark Side of the Moon/01 01 - Pink Floyd - Speak to Me_Breathe.flac]
Aug 21, 2018 02:46:12.807 [0x7fd7b17d8800] DEBUG - Analyzing media parts for item 1904 (What Are Little Girls Made Of?): 3215
Aug 21, 2018 02:46:12.809 [0x7fd7b17d8800] DEBUG - [ID 3215] Media part analysis: /data/TV/Star.Trek/S01/Star.Trek.S01E07.mkv
Aug 21, 2018 02:46:12.809 [0x7fd7b17d8800] DEBUG - [MI] Opening input file: "/data/TV/Star.Trek/S01/Star.Trek.S01E07.mkv"
Aug 21, 2018 02:46:12.809 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Opening '/data/TV/Star.Trek/S01/Star.Trek.S01E07.mkv' for reading
Aug 21, 2018 02:46:12.809 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:12.847 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:12.867 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:12.867 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:12.867 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:12.867 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 962 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:46:12.868 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:12.868 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:12.869 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:12.869 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:12.870 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:12.870 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:12.870 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:12.874 [0x7fd7b17d8800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:12.877 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.881 [0x7fd7b17d8800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:12.884 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.891 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.898 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.906 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.910 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.914 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:12.922 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:12.928 [0x7fd7b17d8800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:13.025 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 23.916667 0.007519
Aug 21, 2018 02:46:13.025 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 23.916667 0.007519
Aug 21, 2018 02:46:13.025 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001270
Aug 21, 2018 02:46:13.025 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001270
Aug 21, 2018 02:46:13.025 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 48.000000 0.005081
Aug 21, 2018 02:46:13.025 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 48.000000 0.011011
Aug 21, 2018 02:46:13.025 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:46:13.026 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:46:13.026 [0x7fd7b17d8800] WARN - [FFMPEG] - Could not find codec parameters for stream 2 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Aug 21, 2018 02:46:13.026 [0x7fd7b17d8800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 1073547 bytes read:1146880 seeks:2 frames:633
Aug 21, 2018 02:46:13.026 [0x7fd7b17d8800] DEBUG - [MI]  * 14be4d15cd2631eb68c15e99e0a6462e14eb3c54 [mkv h264/dca] Duration: 3031787ms, 1440x1080 (1.333333) 23.976fps, bitrate=5113kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Star.Trek/S01/Star.Trek.S01E07.mkv")
Aug 21, 2018 02:46:13.027 [0x7fd7b17d8800] DEBUG - [MI] Importing 6 media chapters.
Aug 21, 2018 02:46:13.028 [0x7fd7b17d8800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:13.030 [0x7fd7b17d8800] DEBUG - [MI] Closing input file: "/data/TV/Star.Trek/S01/Star.Trek.S01E07.mkv"
Aug 21, 2018 02:46:13.035 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Statistics: 1146880 bytes read, 2 seeks
Aug 21, 2018 02:46:13.038 [0x7fd7b17d8800] DEBUG - Committing media stream changes for part 3215
Aug 21, 2018 02:46:13.049 [0x7fd7b17d8800] DEBUG - Updating media item 3215, size=1937697838, metadata_item_id=1904
Aug 21, 2018 02:46:13.050 [0x7fd7b17d8800] DEBUG - Updating part with ID=3215 [/data/TV/Star.Trek/S01/Star.Trek.S01E07.mkv]
Aug 21, 2018 02:46:13.053 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1904&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:13.055 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1904&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:13.057 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1904&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:13.059 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1904&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:13.060 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1904&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:13.062 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1904&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:13.062 [0x7fd7b17d8800] DEBUG - Analyzing media parts for item 5198 (Moist Vagina (Demo, 1993)): 6535
Aug 21, 2018 02:46:13.065 [0x7fd7b17d8800] DEBUG - [ID 6535] Media part analysis: /data/Music/Nirvana/With the Lights Out/03 07 - Nirvana - Moist Vagina (Demo, 1993).flac
Aug 21, 2018 02:46:13.065 [0x7fd7b17d8800] DEBUG - [MI] Opening input file: "/data/Music/Nirvana/With the Lights Out/03 07 - Nirvana - Moist Vagina (Demo, 1993).flac"
Aug 21, 2018 02:46:13.065 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Opening '/data/Music/Nirvana/With the Lights Out/03 07 - Nirvana - Moist Vagina (Demo, 1993).flac' for reading
Aug 21, 2018 02:46:13.065 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:13.139 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:13.139 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1936 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:13.140 [0x7fd7b17d8800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:13.140 [0x7fd7b17d8800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 77824 bytes read:98304 seeks:0 frames:1
Aug 21, 2018 02:46:13.140 [0x7fd7b17d8800] DEBUG - [MI]  * 8956fda0d38cbe2517d2afccc9e63d8a071541d8 [flac /flac] Duration: 116907ms, 0x0 (0.000000) 0.000fps, bitrate=974kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Nirvana/With the Lights Out/03 07 - Nirvana - Moist Vagina (Demo, 1993).flac")
Aug 21, 2018 02:46:13.142 [0x7fd7b17d8800] DEBUG - [MI] Importing 18 media chapters.
Aug 21, 2018 02:46:13.142 [0x7fd7b17d8800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:13.147 [0x7fd7b17d8800] DEBUG - [MI] Closing input file: "/data/Music/Nirvana/With the Lights Out/03 07 - Nirvana - Moist Vagina (Demo, 1993).flac"
Aug 21, 2018 02:46:13.147 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 0 seeks
Aug 21, 2018 02:46:13.149 [0x7fd7b17d8800] DEBUG - Updating media item 6535, size=14245695, metadata_item_id=5198
Aug 21, 2018 02:46:13.150 [0x7fd7b17d8800] DEBUG - Updating part with ID=6535 [/data/Music/Nirvana/With the Lights Out/03 07 - Nirvana - Moist Vagina (Demo, 1993).flac]
Aug 21, 2018 02:46:13.186 [0x7fd7b17d8800] DEBUG - Analyzing media parts for item 78 (Justice League): 78
Aug 21, 2018 02:46:13.186 [0x7fd7b17d8800] DEBUG - [ID 78] Media part analysis: /data/Movies/Justice.League.2017.mkv
Aug 21, 2018 02:46:13.186 [0x7fd7b17d8800] DEBUG - [MI] Opening input file: "/data/Movies/Justice.League.2017.mkv"
Aug 21, 2018 02:46:13.186 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Opening '/data/Movies/Justice.League.2017.mkv' for reading
Aug 21, 2018 02:46:13.186 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:13.243 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:13.274 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:13.274 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:13.274 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:13.275 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 950 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:46:13.275 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:13.275 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:13.276 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:13.276 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:13.277 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:13.277 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:13.277 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:13.282 [0x7fd7b17d8800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:13.287 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:13.290 [0x7fd7b17d8800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:13.295 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:13.307 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:13.320 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:13.333 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:13.338 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:13.348 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:13.357 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:13.366 [0x7fd7b17d8800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:13.454 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Probe buffer size limit of 5000000 bytes reached
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 23.916667 0.006020
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 23.916667 0.006020
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001015
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001015
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 24.083333 0.019492
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 48.000000 0.004059
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 48.000000 0.010936
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000194
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] WARN - [FFMPEG] - Could not find codec parameters for stream 2 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 5028794 bytes read:5093380 seeks:2 frames:568
Aug 21, 2018 02:46:13.455 [0x7fd7b17d8800] DEBUG - [MI]  * f99276cd8aafa5911838c19bb82e2fddc60276be [mkv h264/dca] Duration: 7202614ms, 1920x1080 (1.777778) 23.976fps, bitrate=11393kbps, audio channels=6, optimizedForStreaming=0 ("/data/Movies/Justice.League.2017.mkv")
Aug 21, 2018 02:46:13.457 [0x7fd7b17d8800] DEBUG - [MI] Importing 12 media chapters.
Aug 21, 2018 02:46:13.457 [0x7fd7b17d8800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:13.461 [0x7fd7b17d8800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Aug 21, 2018 02:46:13.461 [0x7fd7b17d8800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Aug 21, 2018 02:46:13.464 [0x7fd7b17d8800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Aug 21, 2018 02:46:13.465 [0x7fd7b17d8800] DEBUG - Updating metadata item (save) (Justice League) with ID 78
Aug 21, 2018 02:46:13.484 [0x7fd7b17d8800] DEBUG - [MI] Closing input file: "/data/Movies/Justice.League.2017.mkv"
Aug 21, 2018 02:46:13.491 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Statistics: 5093380 bytes read, 2 seeks
Aug 21, 2018 02:46:13.494 [0x7fd7b17d8800] DEBUG - Committing media stream changes for part 78
Aug 21, 2018 02:46:13.496 [0x7fd7b17d8800] DEBUG - Updating media item 78, size=10257856195, metadata_item_id=78
Aug 21, 2018 02:46:13.497 [0x7fd7b17d8800] DEBUG - Updating part with ID=78 [/data/Movies/Justice.League.2017.mkv]
Aug 21, 2018 02:46:13.500 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:13.502 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:13.503 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:13.505 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:13.505 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=idle
Aug 21, 2018 02:46:13.508 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=idle
Aug 21, 2018 02:46:13.508 [0x7fd7b17d8800] DEBUG - Analyzing media parts for item 1725 (The Glasses): 3049
Aug 21, 2018 02:46:13.511 [0x7fd7b17d8800] DEBUG - [ID 3049] Media part analysis: /data/TV/Seinfeld/S05/Seinfeld.S05E03.mkv
Aug 21, 2018 02:46:13.511 [0x7fd7b17d8800] DEBUG - [MI] Opening input file: "/data/TV/Seinfeld/S05/Seinfeld.S05E03.mkv"
Aug 21, 2018 02:46:13.511 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Opening '/data/TV/Seinfeld/S05/Seinfeld.S05E03.mkv' for reading
Aug 21, 2018 02:46:13.511 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:13.560 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:13.578 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:13.578 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:13.578 [0x7fd7b17d8800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:13.578 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1325 bytes read:51480 seeks:2 nb_streams:3
Aug 21, 2018 02:46:13.578 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:13.578 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] - palette:
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x161616
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x4b5b06
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0xb7dc12
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x0a0a0a
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0x545454
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] -  0xdadada
Aug 21, 2018 02:46:13.580 [0x7fd7b17d8800] DEBUG - [FFMPEG] - 
Aug 21, 2018 02:46:13.581 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:13.581 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:13.581 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:13.581 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:13.581 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:13.582 [0x7fd7b17d8800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:13.583 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:13.584 [0x7fd7b17d8800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:13.584 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:13.587 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:13.589 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:13.591 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:13.592 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:13.593 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:13.595 [0x7fd7b17d8800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:13.596 [0x7fd7b17d8800] DEBUG - [FFMPEG] - first_dts 234 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:13.598 [0x7fd7b17d8800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:13.598 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:46:13.598 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:46:13.598 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 30.000000 0.000206
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 60.000000 0.000824
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 120.000000 0.003296
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 240.000000 0.013184
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 29.970030 0.000073
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - rfps: 59.940060 0.000292
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 41720 bytes read:117016 seeks:2 frames:99
Aug 21, 2018 02:46:13.599 [0x7fd7b17d8800] DEBUG - [MI]  * c0c4d12e220de523d0066add70f69661fb81f65e [mkv h264/ac3] Duration: 1373339ms, 720x480 (1.333333) 29.970fps, bitrate=2787kbps, audio channels=2, optimizedForStreaming=0 ("/data/TV/Seinfeld/S05/Seinfeld.S05E03.mkv")
Aug 21, 2018 02:46:13.601 [0x7fd7b17d8800] DEBUG - [MI] Importing 4 media chapters.
Aug 21, 2018 02:46:13.601 [0x7fd7b17d8800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:13.603 [0x7fd7b17d8800] DEBUG - [MI] Closing input file: "/data/TV/Seinfeld/S05/Seinfeld.S05E03.mkv"
Aug 21, 2018 02:46:13.605 [0x7fd7b17d8800] DEBUG - [FFMPEG] - Statistics: 117016 bytes read, 2 seeks
Aug 21, 2018 02:46:13.607 [0x7fd7b17d8800] DEBUG - Committing media stream changes for part 3049
Aug 21, 2018 02:46:13.610 [0x7fd7b17d8800] DEBUG - Updating media item 3049, size=478579838, metadata_item_id=1725
Aug 21, 2018 02:46:13.610 [0x7fd7b17d8800] DEBUG - Updating part with ID=3049 [/data/TV/Seinfeld/S05/Seinfeld.S05E03.mkv]
Aug 21, 2018 02:46:13.613 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1725&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:13.615 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1725&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:13.616 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1725&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:13.618 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1725&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:13.618 [0x7fd7a2bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1725&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:13.620 [0x7fd7a2bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1725&metadataType=4&state=-1&mediaState=idle
