Aug 21, 2018 02:46:06.437 [0x7f362abff700] 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:06.438 [0x7f362abff700] 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:06.438 [0x7f362abff700] INFO - Processor         Intel(R) Atom(TM) CPU  C2750  @ 2.40GHz
Aug 21, 2018 02:46:06.438 [0x7f362abff700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 6900,6911,11653,10907,11670,1681,6869,11699,2239,1521 --log-file-suffix  Analysis
Aug 21, 2018 02:46:06.453 [0x7f36364c5800] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Aug 21, 2018 02:46:06.773 [0x7f36364c5800] DEBUG - Analyzing media parts for item 6900 (White Walls): 8076
Aug 21, 2018 02:46:06.776 [0x7f36364c5800] DEBUG - [ID 8076] Media part analysis: /data/Music/Between the Buried and Me/Colors/01 08 - Between the Buried and Me - White Walls.flac
Aug 21, 2018 02:46:06.776 [0x7f36364c5800] DEBUG - [MI] Opening input file: "/data/Music/Between the Buried and Me/Colors/01 08 - Between the Buried and Me - White Walls.flac"
Aug 21, 2018 02:46:06.777 [0x7f36364c5800] DEBUG - [FFMPEG] - Opening '/data/Music/Between the Buried and Me/Colors/01 08 - Between the Buried and Me - White Walls.flac' for reading
Aug 21, 2018 02:46:06.777 [0x7f36364c5800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:06.833 [0x7f36364c5800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:06.834 [0x7f36364c5800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 3055 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:06.836 [0x7f36364c5800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:06.836 [0x7f36364c5800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 122880 bytes read:131072 seeks:0 frames:1
Aug 21, 2018 02:46:06.836 [0x7f36364c5800] DEBUG - [MI]  * 0c98144c285e5cb80cdf6f80b95b9a7c0529c18a [flac /flac] Duration: 853213ms, 0x0 (0.000000) 0.000fps, bitrate=1006kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Between the Buried and Me/Colors/01 08 - Between the Buried and Me - White Walls.flac")
Aug 21, 2018 02:46:06.837 [0x7f36364c5800] DEBUG - [MI] Importing 8 media chapters.
Aug 21, 2018 02:46:06.838 [0x7f36364c5800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:06.841 [0x7f36364c5800] DEBUG - [MI] Closing input file: "/data/Music/Between the Buried and Me/Colors/01 08 - Between the Buried and Me - White Walls.flac"
Aug 21, 2018 02:46:06.842 [0x7f36364c5800] DEBUG - [FFMPEG] - Statistics: 131072 bytes read, 0 seeks
Aug 21, 2018 02:46:06.845 [0x7f36364c5800] DEBUG - Updating media item 8076, size=107352971, metadata_item_id=6900
Aug 21, 2018 02:46:06.846 [0x7f36364c5800] DEBUG - Updating part with ID=8076 [/data/Music/Between the Buried and Me/Colors/01 08 - Between the Buried and Me - White Walls.flac]
Aug 21, 2018 02:46:06.866 [0x7f36364c5800] DEBUG - Analyzing media parts for item 6911 (The Need for Repetition): 8086
Aug 21, 2018 02:46:06.869 [0x7f36364c5800] DEBUG - [ID 8086] Media part analysis: /data/Music/Between the Buried and Me/The Silent Circus/01 10 - Between the Buried and Me - The Need for Repetition.flac
Aug 21, 2018 02:46:06.869 [0x7f36364c5800] DEBUG - [MI] Opening input file: "/data/Music/Between the Buried and Me/The Silent Circus/01 10 - Between the Buried and Me - The Need for Repetition.flac"
Aug 21, 2018 02:46:06.869 [0x7f36364c5800] DEBUG - [FFMPEG] - Opening '/data/Music/Between the Buried and Me/The Silent Circus/01 10 - Between the Buried and Me - The Need for Repetition.flac' for reading
Aug 21, 2018 02:46:06.869 [0x7f36364c5800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:06.909 [0x7f36364c5800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:06.909 [0x7f36364c5800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 3104 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:06.910 [0x7f36364c5800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:06.910 [0x7f36364c5800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 135168 bytes read:163840 seeks:0 frames:1
Aug 21, 2018 02:46:06.910 [0x7f36364c5800] DEBUG - [MI]  * df2d3e1c8ac3d5de961b199005f8fe9bb11c8d54 [flac /flac] Duration: 818667ms, 0x0 (0.000000) 0.000fps, bitrate=705kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Between the Buried and Me/The Silent Circus/01 10 - Between the Buried and Me - The Need for Repetition.flac")
Aug 21, 2018 02:46:06.911 [0x7f36364c5800] DEBUG - [MI] Importing 10 media chapters.
Aug 21, 2018 02:46:06.912 [0x7f36364c5800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:06.915 [0x7f36364c5800] DEBUG - [MI] Closing input file: "/data/Music/Between the Buried and Me/The Silent Circus/01 10 - Between the Buried and Me - The Need for Repetition.flac"
Aug 21, 2018 02:46:06.915 [0x7f36364c5800] DEBUG - [FFMPEG] - Statistics: 163840 bytes read, 0 seeks
Aug 21, 2018 02:46:06.917 [0x7f36364c5800] DEBUG - Updating media item 8086, size=72224322, metadata_item_id=6911
Aug 21, 2018 02:46:06.918 [0x7f36364c5800] DEBUG - Updating part with ID=8086 [/data/Music/Between the Buried and Me/The Silent Circus/01 10 - Between the Buried and Me - The Need for Repetition.flac]
Aug 21, 2018 02:46:06.927 [0x7f36364c5800] DEBUG - Analyzing media parts for item 11653 (We Shall Be Free): 12403
Aug 21, 2018 02:46:06.930 [0x7f36364c5800] DEBUG - [ID 12403] Media part analysis: /data/Music/Garth Brooks/The Ultimate Hits/01 16 - Garth Brooks - We Shall Be Free.flac
Aug 21, 2018 02:46:06.930 [0x7f36364c5800] DEBUG - [MI] Opening input file: "/data/Music/Garth Brooks/The Ultimate Hits/01 16 - Garth Brooks - We Shall Be Free.flac"
Aug 21, 2018 02:46:06.930 [0x7f36364c5800] DEBUG - [FFMPEG] - Opening '/data/Music/Garth Brooks/The Ultimate Hits/01 16 - Garth Brooks - We Shall Be Free.flac' for reading
Aug 21, 2018 02:46:06.930 [0x7f36364c5800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:06.985 [0x7f36364c5800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:06.985 [0x7f36364c5800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2318 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:06.986 [0x7f36364c5800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:06.986 [0x7f36364c5800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 55296 bytes read:65536 seeks:0 frames:1
Aug 21, 2018 02:46:06.986 [0x7f36364c5800] DEBUG - [MI]  * bc43b74e11b272a1c8b2bed7513c557833fed09e [flac /flac] Duration: 228720ms, 0x0 (0.000000) 0.000fps, bitrate=908kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Garth Brooks/The Ultimate Hits/01 16 - Garth Brooks - We Shall Be Free.flac")
Aug 21, 2018 02:46:06.987 [0x7f36364c5800] DEBUG - [MI] Importing 17 media chapters.
Aug 21, 2018 02:46:06.987 [0x7f36364c5800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:06.992 [0x7f36364c5800] DEBUG - [MI] Closing input file: "/data/Music/Garth Brooks/The Ultimate Hits/01 16 - Garth Brooks - We Shall Be Free.flac"
Aug 21, 2018 02:46:06.992 [0x7f36364c5800] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Aug 21, 2018 02:46:06.995 [0x7f36364c5800] DEBUG - Updating media item 12403, size=25967436, metadata_item_id=11653
Aug 21, 2018 02:46:06.995 [0x7f36364c5800] DEBUG - Updating part with ID=12403 [/data/Music/Garth Brooks/The Ultimate Hits/01 16 - Garth Brooks - We Shall Be Free.flac]
Aug 21, 2018 02:46:07.003 [0x7f36364c5800] DEBUG - Analyzing media parts for item 10907 (Arrowhead as Epilogue): 11722
Aug 21, 2018 02:46:07.006 [0x7f36364c5800] DEBUG - [ID 11722] Media part analysis: /data/Music/Circle Takes the Square/Decompositions_ Volume Number One/01 08 - Circle Takes the Square - Arrowhead as Epilogue.flac
Aug 21, 2018 02:46:07.006 [0x7f36364c5800] DEBUG - [MI] Opening input file: "/data/Music/Circle Takes the Square/Decompositions_ Volume Number One/01 08 - Circle Takes the Square - Arrowhead as Epilogue.flac"
Aug 21, 2018 02:46:07.006 [0x7f36364c5800] DEBUG - [FFMPEG] - Opening '/data/Music/Circle Takes the Square/Decompositions_ Volume Number One/01 08 - Circle Takes the Square - Arrowhead as Epilogue.flac' for reading
Aug 21, 2018 02:46:07.006 [0x7f36364c5800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:07.058 [0x7f36364c5800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:07.059 [0x7f36364c5800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1823 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:07.059 [0x7f36364c5800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:07.060 [0x7f36364c5800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 111616 bytes read:131072 seeks:0 frames:1
Aug 21, 2018 02:46:07.060 [0x7f36364c5800] DEBUG - [MI]  * 4db42ca9f1b1344355b62474d7389206b8c5de52 [flac /flac] Duration: 264627ms, 0x0 (0.000000) 0.000fps, bitrate=1056kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Circle Takes the Square/Decompositions_ Volume Number One/01 08 - Circle Takes the Square - Arrowhead as Epilogue.flac")
Aug 21, 2018 02:46:07.061 [0x7f36364c5800] DEBUG - [MI] Importing 9 media chapters.
Aug 21, 2018 02:46:07.061 [0x7f36364c5800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:07.064 [0x7f36364c5800] DEBUG - [MI] Closing input file: "/data/Music/Circle Takes the Square/Decompositions_ Volume Number One/01 08 - Circle Takes the Square - Arrowhead as Epilogue.flac"
Aug 21, 2018 02:46:07.064 [0x7f36364c5800] DEBUG - [FFMPEG] - Statistics: 131072 bytes read, 0 seeks
Aug 21, 2018 02:46:07.067 [0x7f36364c5800] DEBUG - Updating media item 11722, size=34954990, metadata_item_id=10907
Aug 21, 2018 02:46:07.067 [0x7f36364c5800] DEBUG - Updating part with ID=11722 [/data/Music/Circle Takes the Square/Decompositions_ Volume Number One/01 08 - Circle Takes the Square - Arrowhead as Epilogue.flac]
Aug 21, 2018 02:46:07.077 [0x7f36364c5800] DEBUG - Analyzing media parts for item 11670 (Wrapped Up in You): 12420
Aug 21, 2018 02:46:07.079 [0x7f36364c5800] DEBUG - [ID 12420] Media part analysis: /data/Music/Garth Brooks/The Ultimate Hits/02 16 - Garth Brooks - Wrapped Up in You.flac
Aug 21, 2018 02:46:07.079 [0x7f36364c5800] DEBUG - [MI] Opening input file: "/data/Music/Garth Brooks/The Ultimate Hits/02 16 - Garth Brooks - Wrapped Up in You.flac"
Aug 21, 2018 02:46:07.079 [0x7f36364c5800] DEBUG - [FFMPEG] - Opening '/data/Music/Garth Brooks/The Ultimate Hits/02 16 - Garth Brooks - Wrapped Up in You.flac' for reading
Aug 21, 2018 02:46:07.079 [0x7f36364c5800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:07.125 [0x7f36364c5800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:07.125 [0x7f36364c5800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2505 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:07.126 [0x7f36364c5800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:07.126 [0x7f36364c5800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 59392 bytes read:65536 seeks:0 frames:1
Aug 21, 2018 02:46:07.126 [0x7f36364c5800] DEBUG - [MI]  * e16f0a8e588acd45ae6e2b23d9017b8141cbea2b [flac /flac] Duration: 272227ms, 0x0 (0.000000) 0.000fps, bitrate=934kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Garth Brooks/The Ultimate Hits/02 16 - Garth Brooks - Wrapped Up in You.flac")
Aug 21, 2018 02:46:07.127 [0x7f36364c5800] DEBUG - [MI] Importing 19 media chapters.
Aug 21, 2018 02:46:07.127 [0x7f36364c5800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:07.132 [0x7f36364c5800] DEBUG - [MI] Closing input file: "/data/Music/Garth Brooks/The Ultimate Hits/02 16 - Garth Brooks - Wrapped Up in You.flac"
Aug 21, 2018 02:46:07.133 [0x7f36364c5800] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Aug 21, 2018 02:46:07.135 [0x7f36364c5800] DEBUG - Updating media item 12420, size=31813150, metadata_item_id=11670
Aug 21, 2018 02:46:07.136 [0x7f36364c5800] DEBUG - Updating part with ID=12420 [/data/Music/Garth Brooks/The Ultimate Hits/02 16 - Garth Brooks - Wrapped Up in You.flac]
Aug 21, 2018 02:46:07.145 [0x7f36364c5800] DEBUG - Analyzing media parts for item 1681 (The Tape): 3007
Aug 21, 2018 02:46:07.147 [0x7f36364c5800] DEBUG - [ID 3007] Media part analysis: /data/TV/Seinfeld/S03/Seinfeld.S03E08.mkv
Aug 21, 2018 02:46:07.147 [0x7f36364c5800] DEBUG - [MI] Opening input file: "/data/TV/Seinfeld/S03/Seinfeld.S03E08.mkv"
Aug 21, 2018 02:46:07.147 [0x7f36364c5800] DEBUG - [FFMPEG] - Opening '/data/TV/Seinfeld/S03/Seinfeld.S03E08.mkv' for reading
Aug 21, 2018 02:46:07.147 [0x7f36364c5800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:07.202 [0x7f36364c5800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:07.224 [0x7f36364c5800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:07.225 [0x7f36364c5800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:07.225 [0x7f36364c5800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:07.225 [0x7f36364c5800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1323 bytes read:52890 seeks:2 nb_streams:3
Aug 21, 2018 02:46:07.226 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:07.226 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] - palette:
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x161616
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x4b5b06
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0xb7dc12
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:07.227 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:07.228 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:46:07.228 [0x7f36364c5800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:46:07.228 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x0a0a0a
Aug 21, 2018 02:46:07.228 [0x7f36364c5800] DEBUG - [FFMPEG] -  0x545454
Aug 21, 2018 02:46:07.228 [0x7f36364c5800] DEBUG - [FFMPEG] -  0xdadada
Aug 21, 2018 02:46:07.228 [0x7f36364c5800] DEBUG - [FFMPEG] - 
Aug 21, 2018 02:46:07.228 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:07.228 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:07.229 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:07.229 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:07.229 [0x7f36364c5800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:07.230 [0x7f36364c5800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:07.231 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.232 [0x7f36364c5800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:07.233 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.235 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.237 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.240 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.241 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.242 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.244 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.245 [0x7f36364c5800] DEBUG - [FFMPEG] - first_dts 234 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:07.247 [0x7f36364c5800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:07.247 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:46:07.247 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 30.000000 0.000206
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 60.000000 0.000824
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 120.000000 0.003296
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 240.000000 0.013184
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 29.970030 0.000073
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 59.940060 0.000292
Aug 21, 2018 02:46:07.248 [0x7f36364c5800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 41718 bytes read:118426 seeks:2 frames:99
Aug 21, 2018 02:46:07.249 [0x7f36364c5800] DEBUG - [MI]  * f3cc27dbc18cf8ef79cb78322c702ac04052bfb6 [mkv h264/ac3] Duration: 1348314ms, 720x480 (1.333333) 29.970fps, bitrate=3081kbps, audio channels=2, optimizedForStreaming=0 ("/data/TV/Seinfeld/S03/Seinfeld.S03E08.mkv")
Aug 21, 2018 02:46:07.251 [0x7f36364c5800] DEBUG - [MI] Importing 4 media chapters.
Aug 21, 2018 02:46:07.251 [0x7f36364c5800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:07.253 [0x7f36364c5800] DEBUG - [MI] Closing input file: "/data/TV/Seinfeld/S03/Seinfeld.S03E08.mkv"
Aug 21, 2018 02:46:07.255 [0x7f36364c5800] DEBUG - [FFMPEG] - Statistics: 118426 bytes read, 2 seeks
Aug 21, 2018 02:46:07.257 [0x7f36364c5800] DEBUG - Committing media stream changes for part 3007
Aug 21, 2018 02:46:07.260 [0x7f36364c5800] DEBUG - Updating media item 3007, size=519377950, metadata_item_id=1681
Aug 21, 2018 02:46:07.261 [0x7f36364c5800] DEBUG - Updating part with ID=3007 [/data/TV/Seinfeld/S03/Seinfeld.S03E08.mkv]
Aug 21, 2018 02:46:07.264 [0x7f36277ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1681&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:07.268 [0x7f36277ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1681&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:07.268 [0x7f36277ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1681&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:07.270 [0x7f36277ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1681&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:07.271 [0x7f36277ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1681&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:07.271 [0x7f36364c5800] DEBUG - Analyzing media parts for item 6869 (Bicycle Race): 8047
Aug 21, 2018 02:46:07.273 [0x7f36277ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1681&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:07.274 [0x7f36364c5800] DEBUG - [ID 8047] Media part analysis: /data/Music/Between the Buried and Me/The Anatomy Of/01 04 - Between the Buried and Me - Bicycle Race.flac
Aug 21, 2018 02:46:07.274 [0x7f36364c5800] DEBUG - [MI] Opening input file: "/data/Music/Between the Buried and Me/The Anatomy Of/01 04 - Between the Buried and Me - Bicycle Race.flac"
Aug 21, 2018 02:46:07.274 [0x7f36364c5800] DEBUG - [FFMPEG] - Opening '/data/Music/Between the Buried and Me/The Anatomy Of/01 04 - Between the Buried and Me - Bicycle Race.flac' for reading
Aug 21, 2018 02:46:07.274 [0x7f36364c5800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:07.350 [0x7f36364c5800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:07.351 [0x7f36364c5800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1928 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:07.351 [0x7f36364c5800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:07.351 [0x7f36364c5800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 55296 bytes read:65536 seeks:0 frames:1
Aug 21, 2018 02:46:07.351 [0x7f36364c5800] DEBUG - [MI]  * 907a271501ae3316b6d609605aa7e78056f598d6 [flac /flac] Duration: 189507ms, 0x0 (0.000000) 0.000fps, bitrate=973kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Between the Buried and Me/The Anatomy Of/01 04 - Between the Buried and Me - Bicycle Race.flac")
Aug 21, 2018 02:46:07.353 [0x7f36364c5800] DEBUG - [MI] Importing 14 media chapters.
Aug 21, 2018 02:46:07.353 [0x7f36364c5800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:07.357 [0x7f36364c5800] DEBUG - [MI] Closing input file: "/data/Music/Between the Buried and Me/The Anatomy Of/01 04 - Between the Buried and Me - Bicycle Race.flac"
Aug 21, 2018 02:46:07.357 [0x7f36364c5800] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Aug 21, 2018 02:46:07.360 [0x7f36364c5800] DEBUG - Updating media item 8047, size=23052990, metadata_item_id=6869
Aug 21, 2018 02:46:07.360 [0x7f36364c5800] DEBUG - Updating part with ID=8047 [/data/Music/Between the Buried and Me/The Anatomy Of/01 04 - Between the Buried and Me - Bicycle Race.flac]
Aug 21, 2018 02:46:07.369 [0x7f36364c5800] DEBUG - Analyzing media parts for item 11699 (Black Water): 12446
Aug 21, 2018 02:46:07.372 [0x7f36364c5800] DEBUG - [ID 12446] Media part analysis: /data/Music/Garth Brooks/Melting Pot/01 01 - Garth Brooks - Black Water.flac
Aug 21, 2018 02:46:07.372 [0x7f36364c5800] DEBUG - [MI] Opening input file: "/data/Music/Garth Brooks/Melting Pot/01 01 - Garth Brooks - Black Water.flac"
Aug 21, 2018 02:46:07.372 [0x7f36364c5800] DEBUG - [FFMPEG] - Opening '/data/Music/Garth Brooks/Melting Pot/01 01 - Garth Brooks - Black Water.flac' for reading
Aug 21, 2018 02:46:07.372 [0x7f36364c5800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:07.420 [0x7f36364c5800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:46:07.420 [0x7f36364c5800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2089 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:46:07.420 [0x7f36364c5800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:07.421 [0x7f36364c5800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 12329 bytes read:32768 seeks:0 frames:1
Aug 21, 2018 02:46:07.421 [0x7f36364c5800] DEBUG - [MI]  * 123ef30f98406e283836f30c2e6fcf00c248c265 [flac /flac] Duration: 250187ms, 0x0 (0.000000) 0.000fps, bitrate=882kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Garth Brooks/Melting Pot/01 01 - Garth Brooks - Black Water.flac")
Aug 21, 2018 02:46:07.422 [0x7f36364c5800] DEBUG - [MI] Importing 11 media chapters.
Aug 21, 2018 02:46:07.422 [0x7f36364c5800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:07.426 [0x7f36364c5800] DEBUG - [MI] Closing input file: "/data/Music/Garth Brooks/Melting Pot/01 01 - Garth Brooks - Black Water.flac"
Aug 21, 2018 02:46:07.426 [0x7f36364c5800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Aug 21, 2018 02:46:07.428 [0x7f36364c5800] DEBUG - Updating media item 12446, size=27604385, metadata_item_id=11699
Aug 21, 2018 02:46:07.429 [0x7f36364c5800] DEBUG - Updating part with ID=12446 [/data/Music/Garth Brooks/Melting Pot/01 01 - Garth Brooks - Black Water.flac]
Aug 21, 2018 02:46:07.438 [0x7f36364c5800] DEBUG - Analyzing media parts for item 2239 (Backwash): 3528
Aug 21, 2018 02:46:07.440 [0x7f36364c5800] DEBUG - [ID 3528] Media part analysis: /data/TV/The.Wire/S02/The.Wire.S02E07.mkv
Aug 21, 2018 02:46:07.441 [0x7f36364c5800] DEBUG - [MI] Opening input file: "/data/TV/The.Wire/S02/The.Wire.S02E07.mkv"
Aug 21, 2018 02:46:07.441 [0x7f36364c5800] DEBUG - [FFMPEG] - Opening '/data/TV/The.Wire/S02/The.Wire.S02E07.mkv' for reading
Aug 21, 2018 02:46:07.441 [0x7f36364c5800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:07.500 [0x7f36364c5800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:07.533 [0x7f36364c5800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:07.533 [0x7f36364c5800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:07.533 [0x7f36364c5800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:07.533 [0x7f36364c5800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 960 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:46:07.534 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:07.534 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:07.535 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:07.535 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:07.536 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:07.536 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:07.536 [0x7f36364c5800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:07.541 [0x7f36364c5800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:07.546 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.549 [0x7f36364c5800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:07.554 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.567 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.580 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.593 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.599 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.609 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.618 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.628 [0x7f36364c5800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - Probe buffer size limit of 5000000 bytes reached
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:46:07.725 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:46:07.726 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:46:07.726 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.250000 0.018251
Aug 21, 2018 02:46:07.726 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 48.000000 0.000725
Aug 21, 2018 02:46:07.726 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:46:07.726 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:46:07.726 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:46:07.726 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:46:07.726 [0x7f36364c5800] 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:07.726 [0x7f36364c5800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 5003805 bytes read:5099130 seeks:2 frames:240
Aug 21, 2018 02:46:07.727 [0x7f36364c5800] DEBUG - [MI]  * 9a7de2fabe837b91aca95d12daabb2f038c31c7c [mkv h264/dca] Duration: 3506507ms, 1920x1080 (1.777778) 23.976fps, bitrate=14097kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/The.Wire/S02/The.Wire.S02E07.mkv")
Aug 21, 2018 02:46:07.728 [0x7f36364c5800] DEBUG - [MI] Importing 9 media chapters.
Aug 21, 2018 02:46:07.729 [0x7f36364c5800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:07.732 [0x7f36364c5800] DEBUG - [MI] Closing input file: "/data/TV/The.Wire/S02/The.Wire.S02E07.mkv"
Aug 21, 2018 02:46:07.738 [0x7f36364c5800] DEBUG - [FFMPEG] - Statistics: 5099130 bytes read, 2 seeks
Aug 21, 2018 02:46:07.741 [0x7f36364c5800] DEBUG - Committing media stream changes for part 3528
Aug 21, 2018 02:46:07.752 [0x7f36364c5800] DEBUG - Updating media item 3528, size=6179215156, metadata_item_id=2239
Aug 21, 2018 02:46:07.753 [0x7f36364c5800] DEBUG - Updating part with ID=3528 [/data/TV/The.Wire/S02/The.Wire.S02E07.mkv]
Aug 21, 2018 02:46:07.756 [0x7f36277ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=2239&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:07.758 [0x7f36277ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=2239&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:07.759 [0x7f36277ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=2239&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:07.762 [0x7f36277ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=2239&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:07.762 [0x7f36277ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=2239&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:07.764 [0x7f36364c5800] DEBUG - Analyzing media parts for item 1521 (Flashes Before Your Eyes): 2866
Aug 21, 2018 02:46:07.765 [0x7f36277ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=2239&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:07.767 [0x7f36364c5800] DEBUG - [ID 2866] Media part analysis: /data/TV/Lost/S03/Lost.S03E08.mkv
Aug 21, 2018 02:46:07.767 [0x7f36364c5800] DEBUG - [MI] Opening input file: "/data/TV/Lost/S03/Lost.S03E08.mkv"
Aug 21, 2018 02:46:07.767 [0x7f36364c5800] DEBUG - [FFMPEG] - Opening '/data/TV/Lost/S03/Lost.S03E08.mkv' for reading
Aug 21, 2018 02:46:07.767 [0x7f36364c5800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:46:07.819 [0x7f36364c5800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:46:07.837 [0x7f36364c5800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:07.837 [0x7f36364c5800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:07.837 [0x7f36364c5800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:46:07.837 [0x7f36364c5800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 959 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:46:07.837 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:07.837 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:07.839 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:46:07.839 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:46:07.839 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:46:07.839 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:46:07.839 [0x7f36364c5800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:46:07.844 [0x7f36364c5800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:07.849 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.853 [0x7f36364c5800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:46:07.857 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.870 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.883 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.896 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.901 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:46:07.910 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.920 [0x7f36364c5800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:46:07.929 [0x7f36364c5800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:46:07.944 [0x7f36364c5800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:46:07.944 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:46:07.944 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:46:07.944 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:46:07.944 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 24.250000 0.018251
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 48.000000 0.000725
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 499188 bytes read:582688 seeks:2 frames:115
Aug 21, 2018 02:46:07.945 [0x7f36364c5800] DEBUG - [MI]  * 6524f1fd24b35f2cb7f4f8529fa16d30504f6a9a [mkv h264/ac3] Duration: 2593600ms, 1918x1080 (1.775926) 23.976fps, bitrate=7620kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Lost/S03/Lost.S03E08.mkv")
Aug 21, 2018 02:46:07.947 [0x7f36364c5800] DEBUG - [MI] Importing 7 media chapters.
Aug 21, 2018 02:46:07.947 [0x7f36364c5800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:46:07.950 [0x7f36364c5800] DEBUG - [MI] Closing input file: "/data/TV/Lost/S03/Lost.S03E08.mkv"
Aug 21, 2018 02:46:07.954 [0x7f36364c5800] DEBUG - [FFMPEG] - Statistics: 582688 bytes read, 2 seeks
Aug 21, 2018 02:46:07.956 [0x7f36364c5800] DEBUG - Committing media stream changes for part 2866
Aug 21, 2018 02:46:07.960 [0x7f36364c5800] DEBUG - Updating media item 2866, size=2470665251, metadata_item_id=1521
Aug 21, 2018 02:46:07.961 [0x7f36364c5800] DEBUG - Updating part with ID=2866 [/data/TV/Lost/S03/Lost.S03E08.mkv]
Aug 21, 2018 02:46:07.963 [0x7f36277ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1521&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:07.965 [0x7f36277ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1521&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:46:07.966 [0x7f36277ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1521&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:07.968 [0x7f36277ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1521&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:46:07.969 [0x7f36277ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1521&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:46:07.971 [0x7f36277ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1521&metadataType=4&state=-1&mediaState=idle
