Aug 21, 2018 02:46:00.637 [0x7ff8fd664700] 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:00.638 [0x7ff8fd664700] 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:00.638 [0x7ff8fd664700] INFO - Processor         Intel(R) Atom(TM) CPU  C2750  @ 2.40GHz
Aug 21, 2018 02:46:00.638 [0x7ff8fd664700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 10886,8089,1921,1729,6871,1391,1564,5139,1448,1878 --log-file-suffix  Analysis
Aug 21, 2018 02:46:00.653 [0x7ff908d9b800] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Aug 21, 2018 02:46:00.979 [0x7ff908d9b800] DEBUG - Analyzing media parts for item 10886 (If That Ain't Country): 11703
Aug 21, 2018 02:46:00.982 [0x7ff908d9b800] DEBUG - [ID 11703] Media part analysis: /data/Music/David Allan Coe/17 Greatest Hits/01 06 - David Allan Coe - If That Ain't Country.flac
Aug 21, 2018 02:46:00.982 [0x7ff908d9b800] DEBUG - [MI] Opening input file: "/data/Music/David Allan Coe/17 Greatest Hits/01 06 - David Allan Coe - If That Ain't Country.flac"
Aug 21, 2018 02:46:00.983 [0x7ff908d9b800] DEBUG - [FFMPEG] - Opening '/data/Music/David Allan Coe/17 Greatest Hits/01 06 - David Allan Coe - If That Ain't Country.flac' for reading
Aug 21, 2018 02:46:00.983 [0x7ff908d9b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:01.052 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:01.053 [0x7ff908d9b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2459 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:01.054 [0x7ff908d9b800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:01.054 [0x7ff908d9b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 29083 bytes read:32768 seeks:0 frames:1
Aug 21, 2018 02:46:01.054 [0x7ff908d9b800] DEBUG - [MI]  * 69794afcbd261e0c3510fb2f9a01aa7923cc2074 [flac /flac] Duration: 291827ms, 0x0 (0.000000) 0.000fps, bitrate=760kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/David Allan Coe/17 Greatest Hits/01 06 - David Allan Coe - If That Ain't Country.flac")
Aug 21, 2018 02:46:01.056 [0x7ff908d9b800] DEBUG - [MI] Importing 17 media chapters.
Aug 21, 2018 02:46:01.057 [0x7ff908d9b800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:01.062 [0x7ff908d9b800] DEBUG - [MI] Closing input file: "/data/Music/David Allan Coe/17 Greatest Hits/01 06 - David Allan Coe - If That Ain't Country.flac"
Aug 21, 2018 02:46:01.062 [0x7ff908d9b800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Aug 21, 2018 02:46:01.065 [0x7ff908d9b800] DEBUG - Updating media item 11703, size=27724068, metadata_item_id=10886
Aug 21, 2018 02:46:01.066 [0x7ff908d9b800] DEBUG - Updating part with ID=11703 [/data/Music/David Allan Coe/17 Greatest Hits/01 06 - David Allan Coe - If That Ain't Country.flac]
Aug 21, 2018 02:46:01.087 [0x7ff908d9b800] DEBUG - Analyzing media parts for item 8089 (Stand Up and Shout): 9137
Aug 21, 2018 02:46:01.089 [0x7ff908d9b800] DEBUG - [ID 9137] Media part analysis: /data/Music/Dio/Holy Diver/01 01 - Dio - Stand Up and Shout.flac
Aug 21, 2018 02:46:01.090 [0x7ff908d9b800] DEBUG - [MI] Opening input file: "/data/Music/Dio/Holy Diver/01 01 - Dio - Stand Up and Shout.flac"
Aug 21, 2018 02:46:01.090 [0x7ff908d9b800] DEBUG - [FFMPEG] - Opening '/data/Music/Dio/Holy Diver/01 01 - Dio - Stand Up and Shout.flac' for reading
Aug 21, 2018 02:46:01.090 [0x7ff908d9b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:01.233 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:01.233 [0x7ff908d9b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1632 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:01.234 [0x7ff908d9b800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:01.234 [0x7ff908d9b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 73728 bytes read:98304 seeks:0 frames:1
Aug 21, 2018 02:46:01.234 [0x7ff908d9b800] DEBUG - [MI]  * 9030d6ee8f5f217f89cc0f085265a6e4a5247d7c [flac /flac] Duration: 198160ms, 0x0 (0.000000) 0.000fps, bitrate=923kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Dio/Holy Diver/01 01 - Dio - Stand Up and Shout.flac")
Aug 21, 2018 02:46:01.235 [0x7ff908d9b800] DEBUG - [MI] Importing 9 media chapters.
Aug 21, 2018 02:46:01.236 [0x7ff908d9b800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:01.239 [0x7ff908d9b800] DEBUG - [MI] Closing input file: "/data/Music/Dio/Holy Diver/01 01 - Dio - Stand Up and Shout.flac"
Aug 21, 2018 02:46:01.239 [0x7ff908d9b800] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 0 seeks
Aug 21, 2018 02:46:01.242 [0x7ff908d9b800] DEBUG - Updating media item 9137, size=22869906, metadata_item_id=8089
Aug 21, 2018 02:46:01.242 [0x7ff908d9b800] DEBUG - Updating part with ID=9137 [/data/Music/Dio/Holy Diver/01 01 - Dio - Stand Up and Shout.flac]
Aug 21, 2018 02:46:01.252 [0x7ff908d9b800] DEBUG - Analyzing media parts for item 1921 (This Side of Paradise): 3232
Aug 21, 2018 02:46:01.254 [0x7ff908d9b800] DEBUG - [ID 3232] Media part analysis: /data/TV/Star.Trek/S01/Star.Trek.S01E24.mkv
Aug 21, 2018 02:46:01.254 [0x7ff908d9b800] DEBUG - [MI] Opening input file: "/data/TV/Star.Trek/S01/Star.Trek.S01E24.mkv"
Aug 21, 2018 02:46:01.254 [0x7ff908d9b800] DEBUG - [FFMPEG] - Opening '/data/TV/Star.Trek/S01/Star.Trek.S01E24.mkv' for reading
Aug 21, 2018 02:46:01.254 [0x7ff908d9b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:01.288 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:01.304 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:01.304 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:01.304 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:01.304 [0x7ff908d9b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 962 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:46:01.305 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:01.305 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:01.307 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:01.307 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:01.308 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:01.308 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:01.308 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:01.312 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:01.316 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.319 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:01.325 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.332 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.339 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.346 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.353 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.360 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.368 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.376 [0x7ff908d9b800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:01.384 [0x7ff908d9b800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:01.384 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:46:01.384 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:46:01.384 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:46:01.384 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:46:01.384 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.250000 0.018251
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 48.000000 0.000725
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:46:01.385 [0x7ff908d9b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 232776 bytes read:327680 seeks:2 frames:240
Aug 21, 2018 02:46:01.386 [0x7ff908d9b800] DEBUG - [MI]  * c86123089cace7e429ba0559860bcac0e96a03da [mkv h264/dca] Duration: 3033067ms, 1440x1080 (1.333333) 23.976fps, bitrate=5555kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Star.Trek/S01/Star.Trek.S01E24.mkv")
Aug 21, 2018 02:46:01.388 [0x7ff908d9b800] DEBUG - [MI] Importing 6 media chapters.
Aug 21, 2018 02:46:01.388 [0x7ff908d9b800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:01.391 [0x7ff908d9b800] DEBUG - [MI] Closing input file: "/data/TV/Star.Trek/S01/Star.Trek.S01E24.mkv"
Aug 21, 2018 02:46:01.394 [0x7ff908d9b800] DEBUG - [FFMPEG] - Statistics: 327680 bytes read, 2 seeks
Aug 21, 2018 02:46:01.397 [0x7ff908d9b800] DEBUG - Committing media stream changes for part 3232
Aug 21, 2018 02:46:01.400 [0x7ff908d9b800] DEBUG - Updating media item 3232, size=2106108253, metadata_item_id=1921
Aug 21, 2018 02:46:01.400 [0x7ff908d9b800] DEBUG - Updating part with ID=3232 [/data/TV/Star.Trek/S01/Star.Trek.S01E24.mkv]
Aug 21, 2018 02:46:01.404 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1921&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:01.407 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1921&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:01.408 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1921&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:01.410 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1921&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:01.411 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1921&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:01.411 [0x7ff908d9b800] DEBUG - Analyzing media parts for item 1729 (The Non-Fat Yogurt): 3053
Aug 21, 2018 02:46:01.413 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1921&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:01.414 [0x7ff908d9b800] DEBUG - [ID 3053] Media part analysis: /data/TV/Seinfeld/S05/Seinfeld.S05E07.mkv
Aug 21, 2018 02:46:01.414 [0x7ff908d9b800] DEBUG - [MI] Opening input file: "/data/TV/Seinfeld/S05/Seinfeld.S05E07.mkv"
Aug 21, 2018 02:46:01.414 [0x7ff908d9b800] DEBUG - [FFMPEG] - Opening '/data/TV/Seinfeld/S05/Seinfeld.S05E07.mkv' for reading
Aug 21, 2018 02:46:01.414 [0x7ff908d9b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:01.447 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:01.480 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:01.480 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:01.480 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:01.480 [0x7ff908d9b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1325 bytes read:51711 seeks:2 nb_streams:3
Aug 21, 2018 02:46:01.481 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:01.481 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] - palette:
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x161616
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x4b5b06
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0xb7dc12
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:01.482 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:46:01.483 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x0a0a0a
Aug 21, 2018 02:46:01.483 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0x545454
Aug 21, 2018 02:46:01.483 [0x7ff908d9b800] DEBUG - [FFMPEG] -  0xdadada
Aug 21, 2018 02:46:01.483 [0x7ff908d9b800] DEBUG - [FFMPEG] - 
Aug 21, 2018 02:46:01.483 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:01.483 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:01.483 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:01.483 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:01.483 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:01.484 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:01.485 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.486 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:01.487 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.489 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.491 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.493 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.494 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.496 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.497 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.499 [0x7ff908d9b800] DEBUG - [FFMPEG] - first_dts 234 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:01.501 [0x7ff908d9b800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:01.501 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:46:01.501 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:46:01.501 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:46:01.501 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:46:01.501 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:46:01.501 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:46:01.501 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:46:01.501 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:46:01.502 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 30.000000 0.000206
Aug 21, 2018 02:46:01.502 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 60.000000 0.000824
Aug 21, 2018 02:46:01.502 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 120.000000 0.003296
Aug 21, 2018 02:46:01.502 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 240.000000 0.013184
Aug 21, 2018 02:46:01.502 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 29.970030 0.000073
Aug 21, 2018 02:46:01.502 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 59.940060 0.000292
Aug 21, 2018 02:46:01.502 [0x7ff908d9b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 41720 bytes read:117247 seeks:2 frames:99
Aug 21, 2018 02:46:01.502 [0x7ff908d9b800] DEBUG - [MI]  * 0e55074d5718010101c634894ffcf4438c009e15 [mkv h264/ac3] Duration: 1368000ms, 720x480 (1.333333) 29.970fps, bitrate=3443kbps, audio channels=2, optimizedForStreaming=0 ("/data/TV/Seinfeld/S05/Seinfeld.S05E07.mkv")
Aug 21, 2018 02:46:01.503 [0x7ff908d9b800] DEBUG - [MI] Importing 4 media chapters.
Aug 21, 2018 02:46:01.504 [0x7ff908d9b800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:01.506 [0x7ff908d9b800] DEBUG - [MI] Closing input file: "/data/TV/Seinfeld/S05/Seinfeld.S05E07.mkv"
Aug 21, 2018 02:46:01.507 [0x7ff908d9b800] DEBUG - [FFMPEG] - Statistics: 117247 bytes read, 2 seeks
Aug 21, 2018 02:46:01.510 [0x7ff908d9b800] DEBUG - Committing media stream changes for part 3053
Aug 21, 2018 02:46:01.513 [0x7ff908d9b800] DEBUG - Updating media item 3053, size=588803147, metadata_item_id=1729
Aug 21, 2018 02:46:01.513 [0x7ff908d9b800] DEBUG - Updating part with ID=3053 [/data/TV/Seinfeld/S05/Seinfeld.S05E07.mkv]
Aug 21, 2018 02:46:01.516 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1729&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:01.518 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1729&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:01.519 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1729&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:01.521 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1729&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:01.521 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1729&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:01.524 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1729&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:01.525 [0x7ff908d9b800] DEBUG - Analyzing media parts for item 6871 (Us and Them): 8049
Aug 21, 2018 02:46:01.528 [0x7ff908d9b800] DEBUG - [ID 8049] Media part analysis: /data/Music/Between the Buried and Me/The Anatomy Of/01 06 - Between the Buried and Me - Us and Them.flac
Aug 21, 2018 02:46:01.528 [0x7ff908d9b800] DEBUG - [MI] Opening input file: "/data/Music/Between the Buried and Me/The Anatomy Of/01 06 - Between the Buried and Me - Us and Them.flac"
Aug 21, 2018 02:46:01.528 [0x7ff908d9b800] DEBUG - [FFMPEG] - Opening '/data/Music/Between the Buried and Me/The Anatomy Of/01 06 - Between the Buried and Me - Us and Them.flac' for reading
Aug 21, 2018 02:46:01.528 [0x7ff908d9b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:01.571 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:01.571 [0x7ff908d9b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2462 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:01.572 [0x7ff908d9b800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:01.572 [0x7ff908d9b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 28062 bytes read:32768 seeks:0 frames:1
Aug 21, 2018 02:46:01.572 [0x7ff908d9b800] DEBUG - [MI]  * 78f1cc07daeef30c02a19a53dc95142aa2ab3a42 [flac /flac] Duration: 472547ms, 0x0 (0.000000) 0.000fps, bitrate=920kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Between the Buried and Me/The Anatomy Of/01 06 - Between the Buried and Me - Us and Them.flac")
Aug 21, 2018 02:46:01.573 [0x7ff908d9b800] DEBUG - [MI] Importing 14 media chapters.
Aug 21, 2018 02:46:01.573 [0x7ff908d9b800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:01.578 [0x7ff908d9b800] DEBUG - [MI] Closing input file: "/data/Music/Between the Buried and Me/The Anatomy Of/01 06 - Between the Buried and Me - Us and Them.flac"
Aug 21, 2018 02:46:01.578 [0x7ff908d9b800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Aug 21, 2018 02:46:01.580 [0x7ff908d9b800] DEBUG - Updating media item 8049, size=54385871, metadata_item_id=6871
Aug 21, 2018 02:46:01.581 [0x7ff908d9b800] DEBUG - Updating part with ID=8049 [/data/Music/Between the Buried and Me/The Anatomy Of/01 06 - Between the Buried and Me - Us and Them.flac]
Aug 21, 2018 02:46:01.591 [0x7ff908d9b800] DEBUG - Analyzing media parts for item 1391 (The Climb): 2750
Aug 21, 2018 02:46:01.593 [0x7ff908d9b800] DEBUG - [ID 2750] Media part analysis: /data/TV/Game.of.Thrones/S03/Game.of.Thrones.S03E06.mkv
Aug 21, 2018 02:46:01.593 [0x7ff908d9b800] DEBUG - [MI] Opening input file: "/data/TV/Game.of.Thrones/S03/Game.of.Thrones.S03E06.mkv"
Aug 21, 2018 02:46:01.593 [0x7ff908d9b800] DEBUG - [FFMPEG] - Opening '/data/TV/Game.of.Thrones/S03/Game.of.Thrones.S03E06.mkv' for reading
Aug 21, 2018 02:46:01.593 [0x7ff908d9b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:01.776 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:01.808 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:01.808 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:01.809 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:01.809 [0x7ff908d9b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 967 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:46:01.809 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:01.809 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:01.810 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:01.810 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:01.811 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:01.811 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:01.811 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:01.816 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:01.821 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.825 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:01.829 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.842 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.855 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.868 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.874 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:01.884 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.893 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:01.903 [0x7ff908d9b800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:02.002 [0x7ff908d9b800] DEBUG - [FFMPEG] - Probe buffer size limit of 5000000 bytes reached
Aug 21, 2018 02:46:02.002 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.750000 0.013106
Aug 21, 2018 02:46:02.002 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.750000 0.013106
Aug 21, 2018 02:46:02.002 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005253
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005253
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000949
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000949
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000195
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000195
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002990
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002990
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.166667 0.009335
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.166667 0.009335
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.250000 0.019229
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 48.000000 0.000778
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004863
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004863
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:46:02.003 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000191
Aug 21, 2018 02:46:02.004 [0x7ff908d9b800] 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:02.004 [0x7ff908d9b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 5201727 bytes read:5266296 seeks:2 frames:244
Aug 21, 2018 02:46:02.004 [0x7ff908d9b800] DEBUG - [MI]  * b672283b89c067b3bbd7bc8221dc6ddc374f16a0 [mkv h264/dca] Duration: 3202038ms, 1920x1080 (1.777778) 23.976fps, bitrate=5860kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Game.of.Thrones/S03/Game.of.Thrones.S03E06.mkv")
Aug 21, 2018 02:46:02.005 [0x7ff908d9b800] DEBUG - [MI] Importing 8 media chapters.
Aug 21, 2018 02:46:02.005 [0x7ff908d9b800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:02.009 [0x7ff908d9b800] DEBUG - [MI] Closing input file: "/data/TV/Game.of.Thrones/S03/Game.of.Thrones.S03E06.mkv"
Aug 21, 2018 02:46:02.015 [0x7ff908d9b800] DEBUG - [FFMPEG] - Statistics: 5266296 bytes read, 2 seeks
Aug 21, 2018 02:46:02.017 [0x7ff908d9b800] DEBUG - Committing media stream changes for part 2750
Aug 21, 2018 02:46:02.029 [0x7ff908d9b800] DEBUG - Updating media item 2750, size=2345528954, metadata_item_id=1391
Aug 21, 2018 02:46:02.029 [0x7ff908d9b800] DEBUG - Updating part with ID=2750 [/data/TV/Game.of.Thrones/S03/Game.of.Thrones.S03E06.mkv]
Aug 21, 2018 02:46:02.032 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1391&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:02.035 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1391&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:02.035 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1391&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:02.037 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1391&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:02.038 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1391&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:02.040 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1391&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:02.041 [0x7ff908d9b800] DEBUG - Analyzing media parts for item 1564 (Dead Is Dead): 2907
Aug 21, 2018 02:46:02.043 [0x7ff908d9b800] DEBUG - [ID 2907] Media part analysis: /data/TV/Lost/S05/Lost.S05E12.mkv
Aug 21, 2018 02:46:02.043 [0x7ff908d9b800] DEBUG - [MI] Opening input file: "/data/TV/Lost/S05/Lost.S05E12.mkv"
Aug 21, 2018 02:46:02.044 [0x7ff908d9b800] DEBUG - [FFMPEG] - Opening '/data/TV/Lost/S05/Lost.S05E12.mkv' for reading
Aug 21, 2018 02:46:02.044 [0x7ff908d9b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:02.109 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:02.137 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:02.137 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:02.137 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:02.137 [0x7ff908d9b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 959 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:46:02.138 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:02.138 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:02.139 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:02.139 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:02.139 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:02.139 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:02.139 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:02.144 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:02.149 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.153 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:02.157 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.170 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.183 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.196 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.202 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.211 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.220 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.229 [0x7ff908d9b800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:02.245 [0x7ff908d9b800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.250000 0.018251
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 48.000000 0.000725
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:46:02.246 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:46:02.247 [0x7ff908d9b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 715045 bytes read:807472 seeks:2 frames:240
Aug 21, 2018 02:46:02.247 [0x7ff908d9b800] DEBUG - [MI]  * 11c6ec30cb5b6afd392211a13b99aa0191f7393d [mkv h264/dca] Duration: 2585590ms, 1920x1080 (1.777778) 23.976fps, bitrate=10969kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Lost/S05/Lost.S05E12.mkv")
Aug 21, 2018 02:46:02.248 [0x7ff908d9b800] DEBUG - [MI] Importing 7 media chapters.
Aug 21, 2018 02:46:02.249 [0x7ff908d9b800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:02.252 [0x7ff908d9b800] DEBUG - [MI] Closing input file: "/data/TV/Lost/S05/Lost.S05E12.mkv"
Aug 21, 2018 02:46:02.257 [0x7ff908d9b800] DEBUG - [FFMPEG] - Statistics: 807472 bytes read, 2 seeks
Aug 21, 2018 02:46:02.260 [0x7ff908d9b800] DEBUG - Committing media stream changes for part 2907
Aug 21, 2018 02:46:02.271 [0x7ff908d9b800] DEBUG - Updating media item 2907, size=3545209939, metadata_item_id=1564
Aug 21, 2018 02:46:02.272 [0x7ff908d9b800] DEBUG - Updating part with ID=2907 [/data/TV/Lost/S05/Lost.S05E12.mkv]
Aug 21, 2018 02:46:02.285 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1564&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:02.287 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1564&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:02.287 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1564&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:02.289 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1564&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:02.290 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1564&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:02.292 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1564&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:02.294 [0x7ff908d9b800] DEBUG - Analyzing media parts for item 5139 (Come as You Are): 6477
Aug 21, 2018 02:46:02.297 [0x7ff908d9b800] DEBUG - [ID 6477] Media part analysis: /data/Music/Nirvana/Nirvana/01 06 - Nirvana - Come as You Are.flac
Aug 21, 2018 02:46:02.297 [0x7ff908d9b800] DEBUG - [MI] Opening input file: "/data/Music/Nirvana/Nirvana/01 06 - Nirvana - Come as You Are.flac"
Aug 21, 2018 02:46:02.297 [0x7ff908d9b800] DEBUG - [FFMPEG] - Opening '/data/Music/Nirvana/Nirvana/01 06 - Nirvana - Come as You Are.flac' for reading
Aug 21, 2018 02:46:02.297 [0x7ff908d9b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:02.341 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:02.341 [0x7ff908d9b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1905 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:02.342 [0x7ff908d9b800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:02.342 [0x7ff908d9b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 55296 bytes read:65536 seeks:0 frames:1
Aug 21, 2018 02:46:02.342 [0x7ff908d9b800] DEBUG - [MI]  * 5c1e71cfd07626b1f5d32e54cdffdc52984af096 [flac /flac] Duration: 219040ms, 0x0 (0.000000) 0.000fps, bitrate=948kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Nirvana/Nirvana/01 06 - Nirvana - Come as You Are.flac")
Aug 21, 2018 02:46:02.343 [0x7ff908d9b800] DEBUG - [MI] Importing 14 media chapters.
Aug 21, 2018 02:46:02.344 [0x7ff908d9b800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:02.348 [0x7ff908d9b800] DEBUG - [MI] Closing input file: "/data/Music/Nirvana/Nirvana/01 06 - Nirvana - Come as You Are.flac"
Aug 21, 2018 02:46:02.348 [0x7ff908d9b800] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Aug 21, 2018 02:46:02.350 [0x7ff908d9b800] DEBUG - Updating media item 6477, size=25979515, metadata_item_id=5139
Aug 21, 2018 02:46:02.351 [0x7ff908d9b800] DEBUG - Updating part with ID=6477 [/data/Music/Nirvana/Nirvana/01 06 - Nirvana - Come as You Are.flac]
Aug 21, 2018 02:46:02.362 [0x7ff908d9b800] DEBUG - Analyzing media parts for item 1448 (Part 7: Peacefield): 2799
Aug 21, 2018 02:46:02.364 [0x7ff908d9b800] DEBUG - [ID 2799] Media part analysis: /data/TV/John.Adams/S01/John.Adams.S01E07.mkv
Aug 21, 2018 02:46:02.364 [0x7ff908d9b800] DEBUG - [MI] Opening input file: "/data/TV/John.Adams/S01/John.Adams.S01E07.mkv"
Aug 21, 2018 02:46:02.364 [0x7ff908d9b800] DEBUG - [FFMPEG] - Opening '/data/TV/John.Adams/S01/John.Adams.S01E07.mkv' for reading
Aug 21, 2018 02:46:02.364 [0x7ff908d9b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:02.419 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:02.464 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:02.464 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:02.464 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:02.464 [0x7ff908d9b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 953 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:46:02.464 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:02.464 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:02.465 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:02.466 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:02.466 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:02.466 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:02.466 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:02.471 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:02.482 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.486 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:02.492 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.505 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.520 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.535 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.541 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.551 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.562 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.579 [0x7ff908d9b800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:02.644 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.007519
Aug 21, 2018 02:46:02.644 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.007519
Aug 21, 2018 02:46:02.644 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001270
Aug 21, 2018 02:46:02.644 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001270
Aug 21, 2018 02:46:02.644 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 48.000000 0.005081
Aug 21, 2018 02:46:02.644 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 48.000000 0.011011
Aug 21, 2018 02:46:02.645 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:46:02.645 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:46:02.645 [0x7ff908d9b800] 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:02.645 [0x7ff908d9b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 1417941 bytes read:1482565 seeks:2 frames:633
Aug 21, 2018 02:46:02.645 [0x7ff908d9b800] DEBUG - [MI]  * de2e00fb1a73153f070eaaf0e28abdd93453d6a2 [mkv h264/dca] Duration: 3742763ms, 1920x1080 (1.777778) 23.976fps, bitrate=5238kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/John.Adams/S01/John.Adams.S01E07.mkv")
Aug 21, 2018 02:46:02.647 [0x7ff908d9b800] DEBUG - [MI] Importing 8 media chapters.
Aug 21, 2018 02:46:02.647 [0x7ff908d9b800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:02.651 [0x7ff908d9b800] DEBUG - [MI] Closing input file: "/data/TV/John.Adams/S01/John.Adams.S01E07.mkv"
Aug 21, 2018 02:46:02.656 [0x7ff908d9b800] DEBUG - [FFMPEG] - Statistics: 1482565 bytes read, 2 seeks
Aug 21, 2018 02:46:02.659 [0x7ff908d9b800] DEBUG - Committing media stream changes for part 2799
Aug 21, 2018 02:46:02.671 [0x7ff908d9b800] DEBUG - Updating media item 2799, size=2450860256, metadata_item_id=1448
Aug 21, 2018 02:46:02.671 [0x7ff908d9b800] DEBUG - Updating part with ID=2799 [/data/TV/John.Adams/S01/John.Adams.S01E07.mkv]
Aug 21, 2018 02:46:02.674 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1448&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:02.676 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1448&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:02.677 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1448&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:02.679 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1448&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:02.679 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1448&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:02.682 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1448&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:02.683 [0x7ff908d9b800] DEBUG - Analyzing media parts for item 1878 (Intellectual Property): 3193
Aug 21, 2018 02:46:02.685 [0x7ff908d9b800] DEBUG - [ID 3193] Media part analysis: /data/TV/Silicon.Valley/S04/Silicon.Valley.S04E03.mkv
Aug 21, 2018 02:46:02.686 [0x7ff908d9b800] DEBUG - [MI] Opening input file: "/data/TV/Silicon.Valley/S04/Silicon.Valley.S04E03.mkv"
Aug 21, 2018 02:46:02.686 [0x7ff908d9b800] DEBUG - [FFMPEG] - Opening '/data/TV/Silicon.Valley/S04/Silicon.Valley.S04E03.mkv' for reading
Aug 21, 2018 02:46:02.686 [0x7ff908d9b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:02.722 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:02.739 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:02.739 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:02.739 [0x7ff908d9b800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:02.739 [0x7ff908d9b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 959 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:46:02.740 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:02.740 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:02.741 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:02.741 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:02.742 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:02.742 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:02.742 [0x7ff908d9b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:02.747 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:02.751 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.755 [0x7ff908d9b800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:02.760 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.772 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.784 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.797 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.803 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:02.812 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.821 [0x7ff908d9b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:02.830 [0x7ff908d9b800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - Probe buffer size limit of 5000000 bytes reached
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.750000 0.015128
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.750000 0.015128
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.833333 0.006079
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.833333 0.006079
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.001105
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.001105
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000207
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000207
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.083333 0.003382
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.083333 0.003382
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.166667 0.010633
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 24.166667 0.010633
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 48.000000 0.000826
Aug 21, 2018 02:46:02.946 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 48.000000 0.019852
Aug 21, 2018 02:46:02.947 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 120.000000 0.005163
Aug 21, 2018 02:46:02.947 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 120.000000 0.005163
Aug 21, 2018 02:46:02.947 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:46:02.947 [0x7ff908d9b800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000190
Aug 21, 2018 02:46:02.947 [0x7ff908d9b800] 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:02.947 [0x7ff908d9b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 5178769 bytes read:5243346 seeks:2 frames:259
Aug 21, 2018 02:46:02.947 [0x7ff908d9b800] DEBUG - [MI]  * 8dea4c3999801d04831a4cc833bba5a745eb4bec [mkv h264/dca] Duration: 1746080ms, 1920x1080 (1.777778) 23.976fps, bitrate=4963kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Silicon.Valley/S04/Silicon.Valley.S04E03.mkv")
Aug 21, 2018 02:46:02.949 [0x7ff908d9b800] DEBUG - [MI] Importing 5 media chapters.
Aug 21, 2018 02:46:02.949 [0x7ff908d9b800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:02.951 [0x7ff908d9b800] DEBUG - [MI] Closing input file: "/data/TV/Silicon.Valley/S04/Silicon.Valley.S04E03.mkv"
Aug 21, 2018 02:46:02.958 [0x7ff908d9b800] DEBUG - [FFMPEG] - Statistics: 5243346 bytes read, 2 seeks
Aug 21, 2018 02:46:02.960 [0x7ff908d9b800] DEBUG - Committing media stream changes for part 3193
Aug 21, 2018 02:46:02.972 [0x7ff908d9b800] DEBUG - Updating media item 3193, size=1083302394, metadata_item_id=1878
Aug 21, 2018 02:46:02.972 [0x7ff908d9b800] DEBUG - Updating part with ID=3193 [/data/TV/Silicon.Valley/S04/Silicon.Valley.S04E03.mkv]
Aug 21, 2018 02:46:02.975 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1878&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:02.977 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1878&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:02.978 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1878&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:02.979 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1878&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:02.980 [0x7ff8f8fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1878&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:02.983 [0x7ff8f8fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1878&metadataType=4&state=-1&mediaState=idle
