Aug 21, 2018 02:45:54.070 [0x7f11b8bff700] 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:45:54.070 [0x7f11b8bff700] 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:45:54.070 [0x7f11b8bff700] INFO - Processor         Intel(R) Atom(TM) CPU  C2750  @ 2.40GHz
Aug 21, 2018 02:45:54.070 [0x7f11b8bff700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 6873,1058,48,1855,1699,1934,1468,1717,104,1184 --log-file-suffix  Analysis
Aug 21, 2018 02:45:54.084 [0x7f11c451a800] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Aug 21, 2018 02:45:54.405 [0x7f11c451a800] DEBUG - Analyzing media parts for item 6873 (Forced March): 8051
Aug 21, 2018 02:45:54.408 [0x7f11c451a800] DEBUG - [ID 8051] Media part analysis: /data/Music/Between the Buried and Me/The Anatomy Of/01 08 - Between the Buried and Me - Forced March.flac
Aug 21, 2018 02:45:54.408 [0x7f11c451a800] DEBUG - [MI] Opening input file: "/data/Music/Between the Buried and Me/The Anatomy Of/01 08 - Between the Buried and Me - Forced March.flac"
Aug 21, 2018 02:45:54.409 [0x7f11c451a800] DEBUG - [FFMPEG] - Opening '/data/Music/Between the Buried and Me/The Anatomy Of/01 08 - Between the Buried and Me - Forced March.flac' for reading
Aug 21, 2018 02:45:54.409 [0x7f11c451a800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:45:54.462 [0x7f11c451a800] DEBUG - [FFMPEG] - Format flac probed with size=2048 and score=100
Aug 21, 2018 02:45:54.463 [0x7f11c451a800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1990 bytes read:32768 seeks:0 nb_streams:1
Aug 21, 2018 02:45:54.464 [0x7f11c451a800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:45:54.464 [0x7f11c451a800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 51200 bytes read:65536 seeks:0 frames:1
Aug 21, 2018 02:45:54.464 [0x7f11c451a800] DEBUG - [MI]  * 4e92f3be3ad3804ffd0d60a2b4c033440d629170 [flac /flac] Duration: 232240ms, 0x0 (0.000000) 0.000fps, bitrate=1027kbps, audio channels=2, optimizedForStreaming=0 ("/data/Music/Between the Buried and Me/The Anatomy Of/01 08 - Between the Buried and Me - Forced March.flac")
Aug 21, 2018 02:45:54.466 [0x7f11c451a800] DEBUG - [MI] Importing 14 media chapters.
Aug 21, 2018 02:45:54.467 [0x7f11c451a800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:45:54.471 [0x7f11c451a800] DEBUG - [MI] Closing input file: "/data/Music/Between the Buried and Me/The Anatomy Of/01 08 - Between the Buried and Me - Forced March.flac"
Aug 21, 2018 02:45:54.471 [0x7f11c451a800] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Aug 21, 2018 02:45:54.474 [0x7f11c451a800] DEBUG - Updating media item 8051, size=29827950, metadata_item_id=6873
Aug 21, 2018 02:45:54.475 [0x7f11c451a800] DEBUG - Updating part with ID=8051 [/data/Music/Between the Buried and Me/The Anatomy Of/01 08 - Between the Buried and Me - Forced March.flac]
Aug 21, 2018 02:45:54.497 [0x7f11c451a800] DEBUG - Analyzing media parts for item 1058 (The Runaway Bride): 2437
Aug 21, 2018 02:45:54.499 [0x7f11c451a800] DEBUG - [ID 2437] Media part analysis: /data/TV/Doctor.Who.2005/S00/Doctor.Who.2005.S00E004.mkv
Aug 21, 2018 02:45:54.499 [0x7f11c451a800] DEBUG - [MI] Opening input file: "/data/TV/Doctor.Who.2005/S00/Doctor.Who.2005.S00E004.mkv"
Aug 21, 2018 02:45:54.499 [0x7f11c451a800] DEBUG - [FFMPEG] - Opening '/data/TV/Doctor.Who.2005/S00/Doctor.Who.2005.S00E004.mkv' for reading
Aug 21, 2018 02:45:54.499 [0x7f11c451a800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:45:54.575 [0x7f11c451a800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:45:54.684 [0x7f11c451a800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:54.684 [0x7f11c451a800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:54.684 [0x7f11c451a800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:54.684 [0x7f11c451a800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 962 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:45:54.685 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:54.685 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:54.687 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:54.687 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:54.687 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:45:54.688 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:45:54.688 [0x7f11c451a800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:45:54.693 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:54.698 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:54.702 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:54.706 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:54.720 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:54.733 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:54.746 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:54.752 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:54.762 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:54.771 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:54.781 [0x7f11c451a800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:45:54.909 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.007519
Aug 21, 2018 02:45:54.909 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.007519
Aug 21, 2018 02:45:54.909 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001270
Aug 21, 2018 02:45:54.909 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001270
Aug 21, 2018 02:45:54.909 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 48.000000 0.005081
Aug 21, 2018 02:45:54.909 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 48.000000 0.011011
Aug 21, 2018 02:45:54.909 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:45:54.909 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:45:54.910 [0x7f11c451a800] 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:45:54.910 [0x7f11c451a800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2441963 bytes read:2525226 seeks:2 frames:633
Aug 21, 2018 02:45:54.911 [0x7f11c451a800] DEBUG - [MI]  * 005986dc80c285f0a1666d04792285558b230bc4 [mkv h264/dca] Duration: 3789995ms, 1920x1080 (1.777778) 23.976fps, bitrate=9120kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Doctor.Who.2005/S00/Doctor.Who.2005.S00E004.mkv")
Aug 21, 2018 02:45:54.912 [0x7f11c451a800] DEBUG - [MI] Importing 13 media chapters.
Aug 21, 2018 02:45:54.913 [0x7f11c451a800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:45:54.917 [0x7f11c451a800] DEBUG - [MI] Closing input file: "/data/TV/Doctor.Who.2005/S00/Doctor.Who.2005.S00E004.mkv"
Aug 21, 2018 02:45:54.923 [0x7f11c451a800] DEBUG - [FFMPEG] - Statistics: 2525226 bytes read, 2 seeks
Aug 21, 2018 02:45:54.925 [0x7f11c451a800] DEBUG - Committing media stream changes for part 2437
Aug 21, 2018 02:45:54.937 [0x7f11c451a800] DEBUG - Updating media item 2437, size=4320994779, metadata_item_id=1058
Aug 21, 2018 02:45:54.937 [0x7f11c451a800] DEBUG - Updating part with ID=2437 [/data/TV/Doctor.Who.2005/S00/Doctor.Who.2005.S00E004.mkv]
Aug 21, 2018 02:45:54.941 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1058&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:54.945 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1058&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:54.945 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1058&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:54.948 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1058&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:54.949 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1058&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:54.951 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1058&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:54.960 [0x7f11c451a800] DEBUG - It took 20.000000 ms to retrieve 120 items.
Aug 21, 2018 02:45:54.974 [0x7f11c451a800] DEBUG - Analyzing media parts for item 48 (Full Metal Jacket): 48
Aug 21, 2018 02:45:54.974 [0x7f11c451a800] DEBUG - [ID 48] Media part analysis: /data/Movies/Full.Metal.Jacket.1987.mkv
Aug 21, 2018 02:45:54.974 [0x7f11c451a800] DEBUG - [MI] Opening input file: "/data/Movies/Full.Metal.Jacket.1987.mkv"
Aug 21, 2018 02:45:54.974 [0x7f11c451a800] DEBUG - [FFMPEG] - Opening '/data/Movies/Full.Metal.Jacket.1987.mkv' for reading
Aug 21, 2018 02:45:54.974 [0x7f11c451a800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:45:55.056 [0x7f11c451a800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:45:55.109 [0x7f11c451a800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.109 [0x7f11c451a800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.109 [0x7f11c451a800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.109 [0x7f11c451a800] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.109 [0x7f11c451a800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1055 bytes read:65536 seeks:2 nb_streams:4
Aug 21, 2018 02:45:55.109 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:55.109 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:55.111 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:55.111 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:55.112 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:45:55.112 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:45:55.112 [0x7f11c451a800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:45:55.117 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:55.122 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.125 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:55.130 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.143 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.156 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.169 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.175 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.184 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.194 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.203 [0x7f11c451a800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:45:55.306 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.006827
Aug 21, 2018 02:45:55.306 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.006827
Aug 21, 2018 02:45:55.306 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001140
Aug 21, 2018 02:45:55.306 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001140
Aug 21, 2018 02:45:55.306 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 48.000000 0.004562
Aug 21, 2018 02:45:55.306 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 48.000000 0.010905
Aug 21, 2018 02:45:55.306 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:45:55.306 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000193
Aug 21, 2018 02:45:55.306 [0x7f11c451a800] 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:45:55.307 [0x7f11c451a800] WARN - [FFMPEG] - Could not find codec parameters for stream 3 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Aug 21, 2018 02:45:55.307 [0x7f11c451a800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3747958 bytes read:3827679 seeks:2 frames:284
Aug 21, 2018 02:45:55.307 [0x7f11c451a800] DEBUG - [MI]  * a7eb6c3e42b3e48f539776e3cb5f1172075554ce [mkv h264/ac3] Duration: 6990880ms, 1920x1080 (1.777778) 23.976fps, bitrate=7522kbps, audio channels=6, optimizedForStreaming=0 ("/data/Movies/Full.Metal.Jacket.1987.mkv")
Aug 21, 2018 02:45:55.309 [0x7f11c451a800] DEBUG - [MI] Importing 39 media chapters.
Aug 21, 2018 02:45:55.309 [0x7f11c451a800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:45:55.315 [0x7f11c451a800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Aug 21, 2018 02:45:55.315 [0x7f11c451a800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Aug 21, 2018 02:45:55.319 [0x7f11c451a800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Aug 21, 2018 02:45:55.319 [0x7f11c451a800] DEBUG - Updating metadata item (save) (Full Metal Jacket) with ID 48
Aug 21, 2018 02:45:55.344 [0x7f11c451a800] DEBUG - [MI] Closing input file: "/data/Movies/Full.Metal.Jacket.1987.mkv"
Aug 21, 2018 02:45:55.351 [0x7f11c451a800] DEBUG - [FFMPEG] - Statistics: 3827679 bytes read, 2 seeks
Aug 21, 2018 02:45:55.353 [0x7f11c451a800] DEBUG - Committing media stream changes for part 48
Aug 21, 2018 02:45:55.356 [0x7f11c451a800] DEBUG - Updating media item 48, size=6574048777, metadata_item_id=48
Aug 21, 2018 02:45:55.357 [0x7f11c451a800] DEBUG - Updating part with ID=48 [/data/Movies/Full.Metal.Jacket.1987.mkv]
Aug 21, 2018 02:45:55.360 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=48&metadataType=1&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:55.362 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=48&metadataType=1&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:55.362 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=48&metadataType=1&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:55.364 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=48&metadataType=1&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:55.365 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=48&metadataType=1&state=-1&mediaState=idle
Aug 21, 2018 02:45:55.367 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=48&metadataType=1&state=-1&mediaState=idle
Aug 21, 2018 02:45:55.369 [0x7f11c451a800] DEBUG - Analyzing media parts for item 1855 (Runaway Devaluation): 3172
Aug 21, 2018 02:45:55.372 [0x7f11c451a800] DEBUG - [ID 3172] Media part analysis: /data/TV/Silicon.Valley/S02/Silicon.Valley.S02E02.mkv
Aug 21, 2018 02:45:55.372 [0x7f11c451a800] DEBUG - [MI] Opening input file: "/data/TV/Silicon.Valley/S02/Silicon.Valley.S02E02.mkv"
Aug 21, 2018 02:45:55.372 [0x7f11c451a800] DEBUG - [FFMPEG] - Opening '/data/TV/Silicon.Valley/S02/Silicon.Valley.S02E02.mkv' for reading
Aug 21, 2018 02:45:55.372 [0x7f11c451a800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:45:55.404 [0x7f11c451a800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:45:55.423 [0x7f11c451a800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.423 [0x7f11c451a800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.423 [0x7f11c451a800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.423 [0x7f11c451a800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 966 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:45:55.424 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:55.424 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:55.425 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:55.425 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:55.426 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:45:55.426 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:45:55.426 [0x7f11c451a800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:45:55.431 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:55.436 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.440 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:55.445 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.457 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.470 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.482 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.488 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.497 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.506 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.516 [0x7f11c451a800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:45:55.620 [0x7f11c451a800] DEBUG - [FFMPEG] - Probe buffer size limit of 5000000 bytes reached
Aug 21, 2018 02:45:55.620 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:45:55.620 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:45:55.620 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:45:55.620 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:45:55.620 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:45:55.620 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:45:55.620 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:45:55.620 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.250000 0.018251
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 48.000000 0.000725
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] 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:45:55.621 [0x7f11c451a800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 5049614 bytes read:5114184 seeks:2 frames:239
Aug 21, 2018 02:45:55.621 [0x7f11c451a800] DEBUG - [MI]  * 20e24a07d32bf312c4fe324483e26f8eabce20e3 [mkv h264/dca] Duration: 1606939ms, 1920x1080 (1.777778) 23.976fps, bitrate=5048kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Silicon.Valley/S02/Silicon.Valley.S02E02.mkv")
Aug 21, 2018 02:45:55.623 [0x7f11c451a800] DEBUG - [MI] Importing 5 media chapters.
Aug 21, 2018 02:45:55.623 [0x7f11c451a800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:45:55.626 [0x7f11c451a800] DEBUG - [MI] Closing input file: "/data/TV/Silicon.Valley/S02/Silicon.Valley.S02E02.mkv"
Aug 21, 2018 02:45:55.633 [0x7f11c451a800] DEBUG - [FFMPEG] - Statistics: 5114184 bytes read, 2 seeks
Aug 21, 2018 02:45:55.635 [0x7f11c451a800] DEBUG - Committing media stream changes for part 3172
Aug 21, 2018 02:45:55.647 [0x7f11c451a800] DEBUG - Updating media item 3172, size=1014147465, metadata_item_id=1855
Aug 21, 2018 02:45:55.648 [0x7f11c451a800] DEBUG - Updating part with ID=3172 [/data/TV/Silicon.Valley/S02/Silicon.Valley.S02E02.mkv]
Aug 21, 2018 02:45:55.651 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1855&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:55.653 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1855&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:55.654 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1855&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:55.655 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1855&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:55.656 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1855&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:55.658 [0x7f11c451a800] DEBUG - Analyzing media parts for item 1699 (The Trip (2)): 3024
Aug 21, 2018 02:45:55.658 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1855&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:55.661 [0x7f11c451a800] DEBUG - [ID 3024] Media part analysis: /data/TV/Seinfeld/S04/Seinfeld.S04E02.mkv
Aug 21, 2018 02:45:55.661 [0x7f11c451a800] DEBUG - [MI] Opening input file: "/data/TV/Seinfeld/S04/Seinfeld.S04E02.mkv"
Aug 21, 2018 02:45:55.661 [0x7f11c451a800] DEBUG - [FFMPEG] - Opening '/data/TV/Seinfeld/S04/Seinfeld.S04E02.mkv' for reading
Aug 21, 2018 02:45:55.661 [0x7f11c451a800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:45:55.702 [0x7f11c451a800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:45:55.732 [0x7f11c451a800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.732 [0x7f11c451a800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.732 [0x7f11c451a800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.732 [0x7f11c451a800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1325 bytes read:49922 seeks:2 nb_streams:3
Aug 21, 2018 02:45:55.733 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:55.733 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:55.734 [0x7f11c451a800] DEBUG - [FFMPEG] - palette:
Aug 21, 2018 02:45:55.734 [0x7f11c451a800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:45:55.734 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x161616
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x4b5b06
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0xb7dc12
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] - 
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:55.735 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:55.736 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:45:55.736 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:45:55.736 [0x7f11c451a800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:45:55.737 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:55.738 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.739 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:55.740 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.742 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.744 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.746 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.747 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.748 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.750 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.751 [0x7f11c451a800] DEBUG - [FFMPEG] - first_dts 234 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:45:55.753 [0x7f11c451a800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 30.000000 0.000206
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 60.000000 0.000824
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 120.000000 0.003296
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 240.000000 0.013184
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.970030 0.000073
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 59.940060 0.000292
Aug 21, 2018 02:45:55.754 [0x7f11c451a800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 41720 bytes read:115458 seeks:2 frames:99
Aug 21, 2018 02:45:55.755 [0x7f11c451a800] DEBUG - [MI]  * bfd59ddc7acbfa4ece48c4c4809bc8f4b1cf5e54 [mkv h264/ac3] Duration: 1407973ms, 720x480 (1.333333) 29.970fps, bitrate=2973kbps, audio channels=2, optimizedForStreaming=0 ("/data/TV/Seinfeld/S04/Seinfeld.S04E02.mkv")
Aug 21, 2018 02:45:55.756 [0x7f11c451a800] DEBUG - [MI] Importing 4 media chapters.
Aug 21, 2018 02:45:55.756 [0x7f11c451a800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:45:55.758 [0x7f11c451a800] DEBUG - [MI] Closing input file: "/data/TV/Seinfeld/S04/Seinfeld.S04E02.mkv"
Aug 21, 2018 02:45:55.760 [0x7f11c451a800] DEBUG - [FFMPEG] - Statistics: 115458 bytes read, 2 seeks
Aug 21, 2018 02:45:55.762 [0x7f11c451a800] DEBUG - Committing media stream changes for part 3024
Aug 21, 2018 02:45:55.765 [0x7f11c451a800] DEBUG - Updating media item 3024, size=523248481, metadata_item_id=1699
Aug 21, 2018 02:45:55.766 [0x7f11c451a800] DEBUG - Updating part with ID=3024 [/data/TV/Seinfeld/S04/Seinfeld.S04E02.mkv]
Aug 21, 2018 02:45:55.768 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1699&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:55.770 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1699&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:55.771 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1699&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:55.773 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1699&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:55.774 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1699&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:55.776 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1699&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:55.776 [0x7f11c451a800] DEBUG - Analyzing media parts for item 1934 (Catspaw): 3244
Aug 21, 2018 02:45:55.778 [0x7f11c451a800] DEBUG - [ID 3244] Media part analysis: /data/TV/Star.Trek/S02/Star.Trek.S02E07.mkv
Aug 21, 2018 02:45:55.779 [0x7f11c451a800] DEBUG - [MI] Opening input file: "/data/TV/Star.Trek/S02/Star.Trek.S02E07.mkv"
Aug 21, 2018 02:45:55.779 [0x7f11c451a800] DEBUG - [FFMPEG] - Opening '/data/TV/Star.Trek/S02/Star.Trek.S02E07.mkv' for reading
Aug 21, 2018 02:45:55.779 [0x7f11c451a800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:45:55.842 [0x7f11c451a800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:45:55.855 [0x7f11c451a800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.855 [0x7f11c451a800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.855 [0x7f11c451a800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:55.855 [0x7f11c451a800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 962 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:45:55.856 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:55.856 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:55.857 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:55.857 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:55.857 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:45:55.857 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:45:55.857 [0x7f11c451a800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:45:55.862 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:55.865 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.868 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:55.872 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.879 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.886 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.894 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.898 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.902 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:55.910 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:55.917 [0x7f11c451a800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.250000 0.018251
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 48.000000 0.000725
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:45:55.934 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:45:55.935 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:45:55.935 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:45:55.935 [0x7f11c451a800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 404500 bytes read:491520 seeks:2 frames:240
Aug 21, 2018 02:45:55.935 [0x7f11c451a800] DEBUG - [MI]  * 6a0a8e724effe0e7d51bd9bd0b58b21b16d6fe4b [mkv h264/dca] Duration: 3032374ms, 1438x1080 (1.331481) 23.976fps, bitrate=5487kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Star.Trek/S02/Star.Trek.S02E07.mkv")
Aug 21, 2018 02:45:55.936 [0x7f11c451a800] DEBUG - [MI] Importing 6 media chapters.
Aug 21, 2018 02:45:55.936 [0x7f11c451a800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:45:55.939 [0x7f11c451a800] DEBUG - [MI] Closing input file: "/data/TV/Star.Trek/S02/Star.Trek.S02E07.mkv"
Aug 21, 2018 02:45:55.943 [0x7f11c451a800] DEBUG - [FFMPEG] - Statistics: 491520 bytes read, 2 seeks
Aug 21, 2018 02:45:55.945 [0x7f11c451a800] DEBUG - Committing media stream changes for part 3244
Aug 21, 2018 02:45:55.948 [0x7f11c451a800] DEBUG - Updating media item 3244, size=2079847287, metadata_item_id=1934
Aug 21, 2018 02:45:55.948 [0x7f11c451a800] DEBUG - Updating part with ID=3244 [/data/TV/Star.Trek/S02/Star.Trek.S02E07.mkv]
Aug 21, 2018 02:45:55.951 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1934&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:55.953 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1934&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:55.954 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1934&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:55.956 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1934&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:55.956 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1934&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:55.958 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1934&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:55.959 [0x7f11c451a800] DEBUG - Analyzing media parts for item 1468 (House of the Rising Sun): 2815
Aug 21, 2018 02:45:55.961 [0x7f11c451a800] DEBUG - [ID 2815] Media part analysis: /data/TV/Lost/S01/Lost.S01E06.mkv
Aug 21, 2018 02:45:55.962 [0x7f11c451a800] DEBUG - [MI] Opening input file: "/data/TV/Lost/S01/Lost.S01E06.mkv"
Aug 21, 2018 02:45:55.962 [0x7f11c451a800] DEBUG - [FFMPEG] - Opening '/data/TV/Lost/S01/Lost.S01E06.mkv' for reading
Aug 21, 2018 02:45:55.962 [0x7f11c451a800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:45:56.147 [0x7f11c451a800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:45:56.173 [0x7f11c451a800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.173 [0x7f11c451a800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.173 [0x7f11c451a800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.173 [0x7f11c451a800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 959 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:45:56.174 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:56.174 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:56.175 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:56.175 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:56.175 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:45:56.175 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:45:56.175 [0x7f11c451a800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:45:56.181 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:56.185 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.189 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:56.194 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.206 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.218 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.231 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.237 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.245 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.254 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.263 [0x7f11c451a800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.750000 0.012553
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.833333 0.005046
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000923
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000181
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002822
Aug 21, 2018 02:45:56.281 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:45:56.282 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008845
Aug 21, 2018 02:45:56.282 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.250000 0.018251
Aug 21, 2018 02:45:56.282 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 48.000000 0.000725
Aug 21, 2018 02:45:56.282 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:45:56.282 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004531
Aug 21, 2018 02:45:56.282 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:45:56.282 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:45:56.282 [0x7f11c451a800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 396651 bytes read:491520 seeks:2 frames:240
Aug 21, 2018 02:45:56.282 [0x7f11c451a800] DEBUG - [MI]  * af3d8256143b200bff669acc972af5d808261c6b [mkv h264/dca] Duration: 2572576ms, 1918x1080 (1.775926) 23.976fps, bitrate=8437kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Lost/S01/Lost.S01E06.mkv")
Aug 21, 2018 02:45:56.284 [0x7f11c451a800] DEBUG - [MI] Importing 7 media chapters.
Aug 21, 2018 02:45:56.284 [0x7f11c451a800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:45:56.287 [0x7f11c451a800] DEBUG - [MI] Closing input file: "/data/TV/Lost/S01/Lost.S01E06.mkv"
Aug 21, 2018 02:45:56.291 [0x7f11c451a800] DEBUG - [FFMPEG] - Statistics: 491520 bytes read, 2 seeks
Aug 21, 2018 02:45:56.293 [0x7f11c451a800] DEBUG - Committing media stream changes for part 2815
Aug 21, 2018 02:45:56.305 [0x7f11c451a800] DEBUG - Updating media item 2815, size=2713192105, metadata_item_id=1468
Aug 21, 2018 02:45:56.306 [0x7f11c451a800] DEBUG - Updating part with ID=2815 [/data/TV/Lost/S01/Lost.S01E06.mkv]
Aug 21, 2018 02:45:56.309 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1468&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:56.311 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1468&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:56.311 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1468&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:56.313 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1468&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:56.314 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1468&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:56.316 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1468&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:56.316 [0x7f11c451a800] DEBUG - Analyzing media parts for item 1717 (The Junior Mint): 3042
Aug 21, 2018 02:45:56.318 [0x7f11c451a800] DEBUG - [ID 3042] Media part analysis: /data/TV/Seinfeld/S04/Seinfeld.S04E20.mkv
Aug 21, 2018 02:45:56.319 [0x7f11c451a800] DEBUG - [MI] Opening input file: "/data/TV/Seinfeld/S04/Seinfeld.S04E20.mkv"
Aug 21, 2018 02:45:56.319 [0x7f11c451a800] DEBUG - [FFMPEG] - Opening '/data/TV/Seinfeld/S04/Seinfeld.S04E20.mkv' for reading
Aug 21, 2018 02:45:56.319 [0x7f11c451a800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:45:56.375 [0x7f11c451a800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:45:56.405 [0x7f11c451a800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.406 [0x7f11c451a800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.406 [0x7f11c451a800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.406 [0x7f11c451a800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1325 bytes read:51033 seeks:2 nb_streams:3
Aug 21, 2018 02:45:56.406 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:56.406 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:56.407 [0x7f11c451a800] DEBUG - [FFMPEG] - palette:
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x161616
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x4b5b06
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0xb7dc12
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x7f8281
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0xbdbdbd
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x0a0a0a
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0x545454
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] -  0xdadada
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] - 
Aug 21, 2018 02:45:56.408 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:56.409 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:56.409 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:45:56.409 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:45:56.409 [0x7f11c451a800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:45:56.410 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:56.411 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.412 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:56.413 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.415 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.417 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.419 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.420 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.422 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.423 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.425 [0x7f11c451a800] DEBUG - [FFMPEG] - first_dts 234 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.666667 0.013729
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.750000 0.007258
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.833333 0.002847
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.916667 0.000496
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 30.000000 0.000206
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 60.000000 0.000824
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 120.000000 0.003296
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 240.000000 0.013184
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 29.970030 0.000073
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 59.940060 0.000292
Aug 21, 2018 02:45:56.427 [0x7f11c451a800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 41720 bytes read:116569 seeks:2 frames:99
Aug 21, 2018 02:45:56.428 [0x7f11c451a800] DEBUG - [MI]  * 44815f35f8ace48a6f6f3c08b88bfdcfb5d504e3 [mkv h264/ac3] Duration: 1348314ms, 720x480 (1.333333) 29.970fps, bitrate=3648kbps, audio channels=2, optimizedForStreaming=0 ("/data/TV/Seinfeld/S04/Seinfeld.S04E20.mkv")
Aug 21, 2018 02:45:56.429 [0x7f11c451a800] DEBUG - [MI] Importing 4 media chapters.
Aug 21, 2018 02:45:56.429 [0x7f11c451a800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:45:56.431 [0x7f11c451a800] DEBUG - [MI] Closing input file: "/data/TV/Seinfeld/S04/Seinfeld.S04E20.mkv"
Aug 21, 2018 02:45:56.433 [0x7f11c451a800] DEBUG - [FFMPEG] - Statistics: 116569 bytes read, 2 seeks
Aug 21, 2018 02:45:56.435 [0x7f11c451a800] DEBUG - Committing media stream changes for part 3042
Aug 21, 2018 02:45:56.438 [0x7f11c451a800] DEBUG - Updating media item 3042, size=614834177, metadata_item_id=1717
Aug 21, 2018 02:45:56.439 [0x7f11c451a800] DEBUG - Updating part with ID=3042 [/data/TV/Seinfeld/S04/Seinfeld.S04E20.mkv]
Aug 21, 2018 02:45:56.442 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1717&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:56.444 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1717&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:56.444 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1717&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:56.446 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1717&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:56.447 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1717&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:56.449 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1717&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:56.470 [0x7f11c451a800] DEBUG - Analyzing media parts for item 104 (Planes, Trains and Automobiles): 104
Aug 21, 2018 02:45:56.471 [0x7f11c451a800] DEBUG - [ID 104] Media part analysis: /data/Movies/Planes.Trains.and.Automobiles.1987.mkv
Aug 21, 2018 02:45:56.471 [0x7f11c451a800] DEBUG - [MI] Opening input file: "/data/Movies/Planes.Trains.and.Automobiles.1987.mkv"
Aug 21, 2018 02:45:56.471 [0x7f11c451a800] DEBUG - [FFMPEG] - Opening '/data/Movies/Planes.Trains.and.Automobiles.1987.mkv' for reading
Aug 21, 2018 02:45:56.471 [0x7f11c451a800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:45:56.512 [0x7f11c451a800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:45:56.537 [0x7f11c451a800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.537 [0x7f11c451a800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.537 [0x7f11c451a800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.537 [0x7f11c451a800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 966 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:45:56.538 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:56.538 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:56.539 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:56.539 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:56.539 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:45:56.539 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:45:56.539 [0x7f11c451a800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:45:56.545 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:56.549 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.553 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:56.558 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.570 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.582 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.595 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.600 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.609 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.618 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.628 [0x7f11c451a800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:45:56.737 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.007519
Aug 21, 2018 02:45:56.737 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.007519
Aug 21, 2018 02:45:56.737 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001270
Aug 21, 2018 02:45:56.737 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.001270
Aug 21, 2018 02:45:56.737 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 48.000000 0.005081
Aug 21, 2018 02:45:56.737 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 48.000000 0.011011
Aug 21, 2018 02:45:56.737 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000048
Aug 21, 2018 02:45:56.737 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000192
Aug 21, 2018 02:45:56.738 [0x7f11c451a800] 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:45:56.738 [0x7f11c451a800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3918040 bytes read:3987489 seeks:2 frames:633
Aug 21, 2018 02:45:56.738 [0x7f11c451a800] DEBUG - [MI]  * ea601b1d7adec35a86803f30b2cf526400ad83e2 [mkv h264/dca] Duration: 5548459ms, 1920x1080 (1.777778) 23.976fps, bitrate=7100kbps, audio channels=6, optimizedForStreaming=0 ("/data/Movies/Planes.Trains.and.Automobiles.1987.mkv")
Aug 21, 2018 02:45:56.739 [0x7f11c451a800] DEBUG - [MI] Importing 27 media chapters.
Aug 21, 2018 02:45:56.740 [0x7f11c451a800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:45:56.744 [0x7f11c451a800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Aug 21, 2018 02:45:56.744 [0x7f11c451a800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Aug 21, 2018 02:45:56.747 [0x7f11c451a800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Aug 21, 2018 02:45:56.747 [0x7f11c451a800] DEBUG - Updating metadata item (save) (Planes, Trains and Automobiles) with ID 104
Aug 21, 2018 02:45:56.769 [0x7f11c451a800] DEBUG - [MI] Closing input file: "/data/Movies/Planes.Trains.and.Automobiles.1987.mkv"
Aug 21, 2018 02:45:56.776 [0x7f11c451a800] DEBUG - [FFMPEG] - Statistics: 3987489 bytes read, 2 seeks
Aug 21, 2018 02:45:56.778 [0x7f11c451a800] DEBUG - Committing media stream changes for part 104
Aug 21, 2018 02:45:56.781 [0x7f11c451a800] DEBUG - Updating media item 104, size=4924630969, metadata_item_id=104
Aug 21, 2018 02:45:56.782 [0x7f11c451a800] DEBUG - Updating part with ID=104 [/data/Movies/Planes.Trains.and.Automobiles.1987.mkv]
Aug 21, 2018 02:45:56.785 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=104&metadataType=1&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:56.787 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=104&metadataType=1&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:56.788 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=104&metadataType=1&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:56.790 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=104&metadataType=1&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:56.790 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=104&metadataType=1&state=-1&mediaState=idle
Aug 21, 2018 02:45:56.792 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=104&metadataType=1&state=-1&mediaState=idle
Aug 21, 2018 02:45:56.794 [0x7f11c451a800] DEBUG - Analyzing media parts for item 1184 (Death In Heaven): 2555
Aug 21, 2018 02:45:56.797 [0x7f11c451a800] DEBUG - [ID 2555] Media part analysis: /data/TV/Doctor.Who.2005/S08/Doctor.Who.2005.S08E12.mkv
Aug 21, 2018 02:45:56.797 [0x7f11c451a800] DEBUG - [MI] Opening input file: "/data/TV/Doctor.Who.2005/S08/Doctor.Who.2005.S08E12.mkv"
Aug 21, 2018 02:45:56.797 [0x7f11c451a800] DEBUG - [FFMPEG] - Opening '/data/TV/Doctor.Who.2005/S08/Doctor.Who.2005.S08E12.mkv' for reading
Aug 21, 2018 02:45:56.797 [0x7f11c451a800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 21, 2018 02:45:56.842 [0x7f11c451a800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 21, 2018 02:45:56.867 [0x7f11c451a800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.868 [0x7f11c451a800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.868 [0x7f11c451a800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 21, 2018 02:45:56.868 [0x7f11c451a800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 984 bytes read:65536 seeks:2 nb_streams:3
Aug 21, 2018 02:45:56.868 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:56.868 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:56.869 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 21, 2018 02:45:56.869 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 21, 2018 02:45:56.870 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 21, 2018 02:45:56.870 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 21, 2018 02:45:56.870 [0x7f11c451a800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 21, 2018 02:45:56.875 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:56.880 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.884 [0x7f11c451a800] DEBUG - [FFMPEG] - no picture 
Aug 21, 2018 02:45:56.888 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.900 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.913 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.925 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.931 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 21, 2018 02:45:56.940 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.948 [0x7f11c451a800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 21, 2018 02:45:56.957 [0x7f11c451a800] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 1) in the queue
Aug 21, 2018 02:45:57.040 [0x7f11c451a800] DEBUG - [FFMPEG] - All info found
Aug 21, 2018 02:45:57.040 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.750000 0.011951
Aug 21, 2018 02:45:57.040 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.750000 0.011951
Aug 21, 2018 02:45:57.040 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.833333 0.004807
Aug 21, 2018 02:45:57.040 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.833333 0.004807
Aug 21, 2018 02:45:57.040 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000882
Aug 21, 2018 02:45:57.040 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.916667 0.000882
Aug 21, 2018 02:45:57.040 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000176
Aug 21, 2018 02:45:57.040 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000176
Aug 21, 2018 02:45:57.040 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002690
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.083333 0.002690
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008423
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.166667 0.008423
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 24.250000 0.017375
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 48.000000 0.000704
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004400
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 120.000000 0.004400
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000049
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000197
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 1718866 bytes read:1784784 seeks:2 frames:235
Aug 21, 2018 02:45:57.041 [0x7f11c451a800] DEBUG - [MI]  * 5de9d8c44c959676384b92ab9f6c93bdf2f40baa [mkv h264/dca] Duration: 3552096ms, 1920x1080 (1.777778) 23.976fps, bitrate=6004kbps, audio channels=6, optimizedForStreaming=0 ("/data/TV/Doctor.Who.2005/S08/Doctor.Who.2005.S08E12.mkv")
Aug 21, 2018 02:45:57.043 [0x7f11c451a800] DEBUG - [MI] Importing 9 media chapters.
Aug 21, 2018 02:45:57.043 [0x7f11c451a800] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 21, 2018 02:45:57.046 [0x7f11c451a800] DEBUG - [MI] Closing input file: "/data/TV/Doctor.Who.2005/S08/Doctor.Who.2005.S08E12.mkv"
Aug 21, 2018 02:45:57.051 [0x7f11c451a800] DEBUG - [FFMPEG] - Statistics: 1784784 bytes read, 2 seeks
Aug 21, 2018 02:45:57.054 [0x7f11c451a800] DEBUG - Committing media stream changes for part 2555
Aug 21, 2018 02:45:57.066 [0x7f11c451a800] DEBUG - Updating media item 2555, size=2666045944, metadata_item_id=1184
Aug 21, 2018 02:45:57.066 [0x7f11c451a800] DEBUG - Updating part with ID=2555 [/data/TV/Doctor.Who.2005/S08/Doctor.Who.2005.S08E12.mkv]
Aug 21, 2018 02:45:57.069 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1184&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:57.071 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1184&metadataType=4&state=-1&mediaState=analyzing
Aug 21, 2018 02:45:57.072 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1184&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:57.074 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1184&metadataType=4&state=-1&mediaState=thumbnailing
Aug 21, 2018 02:45:57.074 [0x7f11b3fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1184&metadataType=4&state=-1&mediaState=idle
Aug 21, 2018 02:45:57.076 [0x7f11b3fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=1184&metadataType=4&state=-1&mediaState=idle
