Jan 08, 2019 04:08:13.195 [0x7fd1942d6700] INFO - Plex Media Scanner v1.14.1.5488-cc260c476 - ubuntu PC x86_64 - build: linux-ubuntu-x86_64 - GMT -07:00
Jan 08, 2019 04:08:13.196 [0x7fd1942d6700] INFO - Linux version: 3.10.0-957.1.3.el7.x86_64 (#1 SMP Thu Nov 29 14:49:43 UTC 2018), language: en-US
Jan 08, 2019 04:08:13.196 [0x7fd1942d6700] INFO - Processor Intel(R) Xeon(R) CPU           E5645  @ 2.40GHz
Jan 08, 2019 04:08:13.196 [0x7fd1942d6700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 9106,8755,8906,9145,9203,8943,9767,9166,10125,8908 --log-file-suffix  Analysis
Jan 08, 2019 04:08:13.205 [0x7fd1a5d9e840] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Jan 08, 2019 04:08:13.410 [0x7fd1a5d9e840] DEBUG - Analyzing media parts for item 9106 (Swing Kids): 17111
Jan 08, 2019 04:08:13.410 [0x7fd1a5d9e840] DEBUG - [ID 24139] Media part analysis: /mnt/nfs/media/Videos/Movies/Swing Kids (1993).mkv
Jan 08, 2019 04:08:13.410 [0x7fd1a5d9e840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Swing Kids (1993).mkv"
Jan 08, 2019 04:08:13.411 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Swing Kids (1993).mkv' for reading
Jan 08, 2019 04:08:13.411 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:13.433 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:13.450 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:13.450 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:13.451 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 734 bytes read:64802 seeks:2 nb_streams:2
Jan 08, 2019 04:08:13.452 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:13.452 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:13.453 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:13.453 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:13.453 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:13.454 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:13.454 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:13.455 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:13.455 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:13.456 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:13.456 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:13.457 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:13.458 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:13.459 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:13.460 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:13.461 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:13.462 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:13.462 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:13.463 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 18181 bytes read:97570 seeks:2 frames:19
Jan 08, 2019 04:08:13.464 [0x7fd1a5d9e840] DEBUG - [MI]  * fda390e18c1f7951d04d3cc48301fd0c1193e23b [mkv h264/ac3] Duration: 6826570ms, 718x460 (1.847794) 23.976fps, bitrate=2081kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Swing Kids (1993).mkv")
Jan 08, 2019 04:08:13.465 [0x7fd1a5d9e840] DEBUG - [MI] Importing 10 media chapters.
Jan 08, 2019 04:08:13.466 [0x7fd1a5d9e840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:13.469 [0x7fd1a5d9e840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Swing Kids (1993).mkv"
Jan 08, 2019 04:08:13.470 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Statistics: 97570 bytes read, 2 seeks
Jan 08, 2019 04:08:13.472 [0x7fd1a5d9e840] DEBUG - Updating media item 17111, size=1776600925, metadata_item_id=9106
Jan 08, 2019 04:08:13.472 [0x7fd1a5d9e840] DEBUG - Updating part with ID=24139 [/mnt/nfs/media/Videos/Movies/Swing Kids (1993).mkv]
Jan 08, 2019 04:08:13.475 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9106&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:13.480 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9106&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:13.480 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9106&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:13.482 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9106&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:13.482 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9106&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:13.483 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9106&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:13.486 [0x7fd1a5d9e840] DEBUG - Analyzing media parts for item 8755 (Cinderella II: Dreams Come True): 12016
Jan 08, 2019 04:08:13.486 [0x7fd1a5d9e840] DEBUG - [ID 19033] Media part analysis: /mnt/nfs/media/Videos/Movies/Cinderella 2 (2002).mkv
Jan 08, 2019 04:08:13.486 [0x7fd1a5d9e840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Cinderella 2 (2002).mkv"
Jan 08, 2019 04:08:13.486 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Cinderella 2 (2002).mkv' for reading
Jan 08, 2019 04:08:13.486 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:13.501 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:13.528 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:13.528 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:13.528 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:13.528 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 831 bytes read:57713 seeks:2 nb_streams:3
Jan 08, 2019 04:08:13.528 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:13.528 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:13.530 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:13.530 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:13.531 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:13.531 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:13.531 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:13.532 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:13.533 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:13.534 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:13.534 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:13.535 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:13.536 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:13.537 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:13.538 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:13.539 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:13.541 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:13.542 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:13.543 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 33893 bytes read:123249 seeks:2 frames:36
Jan 08, 2019 04:08:13.543 [0x7fd1a5d9e840] DEBUG - [MI]  * 49e57b3a1375b7b49074bf210d439075cbae7d18 [mkv h264/dca] Duration: 4407105ms, 684x480 (1.687500) 29.970fps, bitrate=2481kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Cinderella 2 (2002).mkv")
Jan 08, 2019 04:08:13.544 [0x7fd1a5d9e840] DEBUG - [MI] Importing 14 media chapters.
Jan 08, 2019 04:08:13.544 [0x7fd1a5d9e840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:13.547 [0x7fd1a5d9e840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:13.547 [0x7fd1a5d9e840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:13.551 [0x7fd1a5d9e840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:13.551 [0x7fd1a5d9e840] DEBUG - Updating metadata item (save) (Cinderella II: Dreams Come True) with ID 8755
Jan 08, 2019 04:08:13.558 [0x7fd1a5d9e840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:13.558 [0x7fd1a5d9e840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:13.561 [0x7fd1a5d9e840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:13.562 [0x7fd1a5d9e840] DEBUG - Updating metadata item (save) (Cinderella II: Dreams Come True) with ID 8755
Jan 08, 2019 04:08:13.564 [0x7fd1a5d9e840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Cinderella 2 (2002).mkv"
Jan 08, 2019 04:08:13.566 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Statistics: 123249 bytes read, 2 seeks
Jan 08, 2019 04:08:13.569 [0x7fd1a5d9e840] DEBUG - Updating media item 12016, size=1367123165, metadata_item_id=8755
Jan 08, 2019 04:08:13.569 [0x7fd1a5d9e840] DEBUG - Updating part with ID=19033 [/mnt/nfs/media/Videos/Movies/Cinderella 2 (2002).mkv]
Jan 08, 2019 04:08:13.571 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8755&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:13.573 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8755&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:13.573 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8755&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:13.575 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8755&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:13.575 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8755&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:13.577 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8755&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:13.580 [0x7fd1a5d9e840] DEBUG - Analyzing media parts for item 8906 (Anne Of Green Gables: The Continuing Story): 14897
Jan 08, 2019 04:08:13.580 [0x7fd1a5d9e840] DEBUG - [ID 21925] Media part analysis: /mnt/nfs/media/Videos/Movies/Anne of Green Gables - The Continuing Story (2000).mkv
Jan 08, 2019 04:08:13.581 [0x7fd1a5d9e840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Anne of Green Gables - The Continuing Story (2000).mkv"
Jan 08, 2019 04:08:13.581 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Anne of Green Gables - The Continuing Story (2000).mkv' for reading
Jan 08, 2019 04:08:13.581 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:14.468 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:14.475 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:14.475 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:14.475 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 732 bytes read:65536 seeks:2 nb_streams:2
Jan 08, 2019 04:08:14.475 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:14.475 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:14.476 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:14.476 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:14.477 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:14.477 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:14.477 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:14.478 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:14.478 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:14.479 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:14.479 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:14.481 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:14.481 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:14.482 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:14.483 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:14.484 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:14.486 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:14.487 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:14.490 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 8970 bytes read:98304 seeks:2 frames:19
Jan 08, 2019 04:08:14.490 [0x7fd1a5d9e840] DEBUG - [MI]  * 4628296cf44fd6c4dd552aa08542c513033dd94e [mkv h264/ac3] Duration: 11055461ms, 710x480 (1.314815) 23.976fps, bitrate=2432kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Anne of Green Gables - The Continuing Story (2000).mkv")
Jan 08, 2019 04:08:14.492 [0x7fd1a5d9e840] DEBUG - [MI] Importing 45 media chapters.
Jan 08, 2019 04:08:14.492 [0x7fd1a5d9e840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:14.499 [0x7fd1a5d9e840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Anne of Green Gables - The Continuing Story (2000).mkv"
Jan 08, 2019 04:08:14.501 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Jan 08, 2019 04:08:14.503 [0x7fd1a5d9e840] DEBUG - Updating media item 14897, size=3361108320, metadata_item_id=8906
Jan 08, 2019 04:08:14.503 [0x7fd1a5d9e840] DEBUG - Updating part with ID=21925 [/mnt/nfs/media/Videos/Movies/Anne of Green Gables - The Continuing Story (2000).mkv]
Jan 08, 2019 04:08:14.505 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8906&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:14.507 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8906&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:14.507 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8906&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:14.509 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8906&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:14.509 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8906&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:14.510 [0x7fd1a5d9e840] DEBUG - Analyzing media parts for item 9145 (The Impossible Dream): 18625
Jan 08, 2019 04:08:14.511 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8906&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:14.511 [0x7fd1a5d9e840] DEBUG - [ID 25757] Media part analysis: /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E20 - The Impossible Dream.mp4
Jan 08, 2019 04:08:14.511 [0x7fd1a5d9e840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E20 - The Impossible Dream.mp4"
Jan 08, 2019 04:08:14.512 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E20 - The Impossible Dream.mp4' for reading
Jan 08, 2019 04:08:14.512 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:14.543 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Jan 08, 2019 04:08:14.543 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Jan 08, 2019 04:08:14.564 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:15.456 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 6006, duration: 132017886
Jan 08, 2019 04:08:15.460 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Offset DTS by 6006 to make first pts zero.
Jan 08, 2019 04:08:15.470 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Jan 08, 2019 04:08:15.470 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:15.475 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:15.475 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Processing st: 2, edit list 0 - media time: 6006, duration: 132017886
Jan 08, 2019 04:08:15.475 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Jan 08, 2019 04:08:15.546 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 347026225 bytes read:1081896 seeks:7 nb_streams:3
Jan 08, 2019 04:08:15.546 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:15.546 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:15.547 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:15.547 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:15.547 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:15.549 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:15.549 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:16.008 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 1115 bytes read:1114664 seeks:8 frames:8
Jan 08, 2019 04:08:16.008 [0x7fd1a5d9e840] DEBUG - [MI] Opening "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E20 - The Impossible Dream.mp4" for MP4 atom parsing
Jan 08, 2019 04:08:16.009 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Jan 08, 2019 04:08:16.009 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Jan 08, 2019 04:08:16.009 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Skipping mdat atom of size 346094115 at offset 160
Jan 08, 2019 04:08:16.009 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found moov atom of size 931926 at offset 346094275
Jan 08, 2019 04:08:16.009 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found trak atom of size 616369 at offset 346094415
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found trak atom of size 314766 at offset 346710784
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found trak atom of size 540 at offset 347025550
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found mdia atom of size 616213 at offset 346094515
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found minf atom of size 616140 at offset 346094588
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found stbl atom of size 616076 at offset 346094652
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 346710728
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 346710736
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found mdia atom of size 314644 at offset 346710884
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found minf atom of size 314571 at offset 346710957
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found stbl atom of size 314511 at offset 346711017
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found mdia atom of size 404 at offset 347025650
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found minf atom of size 331 at offset 347025723
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found stbl atom of size 211 at offset 347025843
Jan 08, 2019 04:08:16.010 [0x7fd1a5d9e840] DEBUG - [MI]  * a6deecc9e71c050ec8fc45e614f3b78b75acfbf8 [mp4 h264/aac] Duration: 1466865ms, 720x480 (1.337500) 29.970fps, bitrate=1892kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E20 - The Impossible Dream.mp4")
Jan 08, 2019 04:08:16.011 [0x7fd1a5d9e840] DEBUG - [MI] Importing 4 media chapters.
Jan 08, 2019 04:08:16.011 [0x7fd1a5d9e840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:16.013 [0x7fd1a5d9e840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E20 - The Impossible Dream.mp4"
Jan 08, 2019 04:08:16.014 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Statistics: 1114664 bytes read, 8 seeks
Jan 08, 2019 04:08:16.016 [0x7fd1a5d9e840] DEBUG - Updating media item 18625, size=347026333, metadata_item_id=9145
Jan 08, 2019 04:08:16.016 [0x7fd1a5d9e840] DEBUG - Updating part with ID=25757 [/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E20 - The Impossible Dream.mp4]
Jan 08, 2019 04:08:16.018 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9145&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:16.021 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9145&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:16.021 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9145&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:16.023 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9145&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:16.023 [0x7fd1a5d9e840] DEBUG - Analyzing media parts for item 9203 (Love Returns): 18680
Jan 08, 2019 04:08:16.024 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9145&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:16.024 [0x7fd1a5d9e840] DEBUG - [ID 25812] Media part analysis: /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E08 - Love Returns.mp4
Jan 08, 2019 04:08:16.025 [0x7fd1a5d9e840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E08 - Love Returns.mp4"
Jan 08, 2019 04:08:16.025 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E08 - Love Returns.mp4' for reading
Jan 08, 2019 04:08:16.025 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:16.025 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9145&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:16.059 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Jan 08, 2019 04:08:16.060 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Jan 08, 2019 04:08:16.097 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:16.117 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 6006, duration: 135855720
Jan 08, 2019 04:08:16.122 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Offset DTS by 6006 to make first pts zero.
Jan 08, 2019 04:08:16.132 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Jan 08, 2019 04:08:16.132 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:16.137 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:16.140 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:16.140 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Processing st: 3, edit list 0 - media time: 6006, duration: 135792657
Jan 08, 2019 04:08:16.140 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Jan 08, 2019 04:08:16.170 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 295863084 bytes read:1245315 seeks:7 nb_streams:4
Jan 08, 2019 04:08:16.170 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:16.170 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:16.172 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:16.172 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:16.172 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:16.173 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:16.173 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:16.177 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 4961 bytes read:1278083 seeks:8 frames:13
Jan 08, 2019 04:08:16.177 [0x7fd1a5d9e840] DEBUG - [MI] Opening "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E08 - Love Returns.mp4" for MP4 atom parsing
Jan 08, 2019 04:08:16.177 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Jan 08, 2019 04:08:16.177 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Jan 08, 2019 04:08:16.177 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Skipping mdat atom of size 294757061 at offset 160
Jan 08, 2019 04:08:16.177 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found moov atom of size 1105839 at offset 294757221
Jan 08, 2019 04:08:16.178 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found trak atom of size 664746 at offset 294757361
Jan 08, 2019 04:08:16.178 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found trak atom of size 364378 at offset 295422107
Jan 08, 2019 04:08:16.178 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found trak atom of size 75908 at offset 295786485
Jan 08, 2019 04:08:16.178 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found trak atom of size 556 at offset 295862393
Jan 08, 2019 04:08:16.178 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found mdia atom of size 664590 at offset 294757461
Jan 08, 2019 04:08:16.178 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found minf atom of size 664517 at offset 294757534
Jan 08, 2019 04:08:16.178 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found stbl atom of size 664453 at offset 294757598
Jan 08, 2019 04:08:16.178 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found co64 atom of size 90496 at offset 294938831
Jan 08, 2019 04:08:16.178 [0x7fd1a5d9e840] DEBUG - [MI] File has co64 atom with size 90496 at offset 294938831
Jan 08, 2019 04:08:16.179 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 295422051
Jan 08, 2019 04:08:16.179 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 295422059
Jan 08, 2019 04:08:16.179 [0x7fd1a5d9e840] DEBUG - [MI] Determined stream id 4 has chapters, not subtitles
Jan 08, 2019 04:08:16.179 [0x7fd1a5d9e840] DEBUG - [MI]  * 5eff1e58353c79dfa8e459123eda1680926cdf69 [mp4 h264/aac] Duration: 1509547ms, 700x478 (1.301720) 29.970fps, bitrate=1567kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E08 - Love Returns.mp4")
Jan 08, 2019 04:08:16.180 [0x7fd1a5d9e840] DEBUG - [MI] Importing 4 media chapters.
Jan 08, 2019 04:08:16.180 [0x7fd1a5d9e840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:16.182 [0x7fd1a5d9e840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E08 - Love Returns.mp4"
Jan 08, 2019 04:08:16.183 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Statistics: 1278083 bytes read, 8 seeks
Jan 08, 2019 04:08:16.185 [0x7fd1a5d9e840] DEBUG - Updating media item 18680, size=295863192, metadata_item_id=9203
Jan 08, 2019 04:08:16.185 [0x7fd1a5d9e840] DEBUG - Updating part with ID=25812 [/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E08 - Love Returns.mp4]
Jan 08, 2019 04:08:16.187 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9203&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:16.189 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9203&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:16.190 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9203&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:16.192 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9203&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:16.192 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9203&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:16.194 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9203&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:16.197 [0x7fd1a5d9e840] DEBUG - Analyzing media parts for item 8943 (Le Mans): 15027
Jan 08, 2019 04:08:16.197 [0x7fd1a5d9e840] DEBUG - [ID 22055] Media part analysis: /mnt/nfs/media/Videos/Movies/Le Mans (1971).mkv
Jan 08, 2019 04:08:16.197 [0x7fd1a5d9e840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Le Mans (1971).mkv"
Jan 08, 2019 04:08:16.198 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Le Mans (1971).mkv' for reading
Jan 08, 2019 04:08:16.198 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:16.676 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:16.705 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:16.705 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:16.705 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:16.705 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 828 bytes read:65536 seeks:2 nb_streams:3
Jan 08, 2019 04:08:16.705 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:16.706 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:16.707 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:16.707 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:16.708 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:16.708 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:16.708 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:16.709 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:16.710 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:16.710 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:16.711 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:16.712 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:16.712 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:16.713 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:16.714 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:16.715 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:16.715 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:16.716 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:18.018 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 25280 bytes read:98304 seeks:2 frames:28
Jan 08, 2019 04:08:18.018 [0x7fd1a5d9e840] DEBUG - [MI]  * bcb7a603cdfe839f300fdec685b58ddfb96cc46b [mkv h264/ac3] Duration: 6518561ms, 720x364 (2.343389) 23.976fps, bitrate=2249kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Le Mans (1971).mkv")
Jan 08, 2019 04:08:18.020 [0x7fd1a5d9e840] DEBUG - [MI] Importing 12 media chapters.
Jan 08, 2019 04:08:18.020 [0x7fd1a5d9e840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:18.024 [0x7fd1a5d9e840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Le Mans (1971).mkv"
Jan 08, 2019 04:08:18.025 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Jan 08, 2019 04:08:18.028 [0x7fd1a5d9e840] DEBUG - Updating media item 15027, size=1833003313, metadata_item_id=8943
Jan 08, 2019 04:08:18.028 [0x7fd1a5d9e840] DEBUG - Updating part with ID=22055 [/mnt/nfs/media/Videos/Movies/Le Mans (1971).mkv]
Jan 08, 2019 04:08:18.031 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8943&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:18.034 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8943&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:18.035 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8943&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:18.036 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8943&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:18.037 [0x7fd1a5d9e840] DEBUG - Analyzing media parts for item 9767 (Alcoholics Unanimous): 21843
Jan 08, 2019 04:08:18.037 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8943&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:18.039 [0x7fd1a5d9e840] DEBUG - [ID 29147] Media part analysis: /mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x09 - Alcoholics Unanimous.mkv
Jan 08, 2019 04:08:18.039 [0x7fd1a5d9e840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x09 - Alcoholics Unanimous.mkv"
Jan 08, 2019 04:08:18.039 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8943&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:18.039 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x09 - Alcoholics Unanimous.mkv' for reading
Jan 08, 2019 04:08:18.039 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:18.074 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:18.089 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:18.089 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:18.089 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:18.089 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:18.089 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7037 bytes read:45980 seeks:2 nb_streams:4
Jan 08, 2019 04:08:18.089 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:18.089 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.090 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:18.091 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:18.092 [0x7fd1a5d9e840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:18.092 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:18.092 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:18.092 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:18.093 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:18.093 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:18.093 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:18.094 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:18.096 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:18.096 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:18.097 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:18.098 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:18.100 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:18.101 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:18.102 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:18.103 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:18.104 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:18.105 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:18.106 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 8277 bytes read:78748 seeks:2 frames:34
Jan 08, 2019 04:08:18.106 [0x7fd1a5d9e840] DEBUG - [MI]  * a11b7133c389b6f77a81722ed8376b975c9aabbd [mkv h264/aac] Duration: 1564501ms, 640x480 (1.333333) 23.976fps, bitrate=938kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x09 - Alcoholics Unanimous.mkv")
Jan 08, 2019 04:08:18.107 [0x7fd1a5d9e840] DEBUG - [MI] Importing 11 media chapters.
Jan 08, 2019 04:08:18.107 [0x7fd1a5d9e840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:18.110 [0x7fd1a5d9e840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x09 - Alcoholics Unanimous.mkv"
Jan 08, 2019 04:08:18.111 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Statistics: 78748 bytes read, 2 seeks
Jan 08, 2019 04:08:18.112 [0x7fd1a5d9e840] DEBUG - Committing media stream changes for part 29147
Jan 08, 2019 04:08:18.114 [0x7fd1a5d9e840] DEBUG - Updating media item 21843, size=183582090, metadata_item_id=9767
Jan 08, 2019 04:08:18.115 [0x7fd1a5d9e840] DEBUG - Updating part with ID=29147 [/mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x09 - Alcoholics Unanimous.mkv]
Jan 08, 2019 04:08:18.116 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9767&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:18.118 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9767&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:18.119 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9767&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:18.120 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9767&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:18.120 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9767&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:18.121 [0x7fd1a5d9e840] DEBUG - Analyzing media parts for item 9166 (Out to Lunch): 18645
Jan 08, 2019 04:08:18.122 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9767&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:18.122 [0x7fd1a5d9e840] DEBUG - [ID 25777] Media part analysis: /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E18 - A Simple Little Wedding.mp4
Jan 08, 2019 04:08:18.122 [0x7fd1a5d9e840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E18 - A Simple Little Wedding.mp4"
Jan 08, 2019 04:08:18.122 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E18 - A Simple Little Wedding.mp4' for reading
Jan 08, 2019 04:08:18.122 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:18.620 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Jan 08, 2019 04:08:18.620 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Jan 08, 2019 04:08:18.631 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:18.683 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 6006, duration: 134642508
Jan 08, 2019 04:08:18.688 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Offset DTS by 6006 to make first pts zero.
Jan 08, 2019 04:08:18.697 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Jan 08, 2019 04:08:18.697 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:18.702 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:18.702 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Processing st: 2, edit list 0 - media time: 6006, duration: 134642508
Jan 08, 2019 04:08:18.702 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Jan 08, 2019 04:08:18.739 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 273615381 bytes read:1124413 seeks:8 nb_streams:3
Jan 08, 2019 04:08:18.739 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:18.739 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:18.740 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:18.740 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:18.740 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:18.741 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:18.744 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:18.745 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 8765 bytes read:1157181 seeks:9 frames:8
Jan 08, 2019 04:08:18.746 [0x7fd1a5d9e840] DEBUG - [MI] Opening "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E18 - A Simple Little Wedding.mp4" for MP4 atom parsing
Jan 08, 2019 04:08:18.746 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Jan 08, 2019 04:08:18.746 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Jan 08, 2019 04:08:18.746 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Skipping mdat atom of size 272669600 at offset 160
Jan 08, 2019 04:08:18.746 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found moov atom of size 945597 at offset 272669760
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found trak atom of size 623788 at offset 272669900
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found trak atom of size 321006 at offset 273293688
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found trak atom of size 552 at offset 273614694
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found mdia atom of size 623632 at offset 272670000
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found minf atom of size 623559 at offset 272670073
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found stbl atom of size 623495 at offset 272670137
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 273293632
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 273293640
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found mdia atom of size 320884 at offset 273293788
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found minf atom of size 320811 at offset 273293861
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found stbl atom of size 320751 at offset 273293921
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found mdia atom of size 416 at offset 273614794
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found minf atom of size 343 at offset 273614867
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - Mp4AtomParser: Found stbl atom of size 223 at offset 273614987
Jan 08, 2019 04:08:18.747 [0x7fd1a5d9e840] DEBUG - [MI]  * 5ddebc77bfc445e0bd19806eeca2b79f5fff6f20 [mp4 h264/aac] Duration: 1496028ms, 706x478 (1.312878) 29.970fps, bitrate=1463kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E18 - A Simple Little Wedding.mp4")
Jan 08, 2019 04:08:18.748 [0x7fd1a5d9e840] DEBUG - [MI] Importing 5 media chapters.
Jan 08, 2019 04:08:18.749 [0x7fd1a5d9e840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:18.750 [0x7fd1a5d9e840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E18 - A Simple Little Wedding.mp4"
Jan 08, 2019 04:08:18.751 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Statistics: 1157181 bytes read, 9 seeks
Jan 08, 2019 04:08:18.753 [0x7fd1a5d9e840] DEBUG - Updating media item 18645, size=273615489, metadata_item_id=9166
Jan 08, 2019 04:08:18.754 [0x7fd1a5d9e840] DEBUG - Updating part with ID=25777 [/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E18 - A Simple Little Wedding.mp4]
Jan 08, 2019 04:08:18.755 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9166&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:18.757 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9166&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:18.758 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9166&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:18.759 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9166&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:18.760 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9166&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:18.762 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9166&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:18.771 [0x7fd1a5d9e840] DEBUG - Analyzing media parts for item 10125 (Dr. No): 22585
Jan 08, 2019 04:08:18.771 [0x7fd1a5d9e840] DEBUG - [ID 29889] Media part analysis: /mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv
Jan 08, 2019 04:08:18.771 [0x7fd1a5d9e840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv"
Jan 08, 2019 04:08:18.771 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv' for reading
Jan 08, 2019 04:08:18.771 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:18.772 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:18.774 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:18.774 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:18.774 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:18.774 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:18.774 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 930 bytes read:58749 seeks:2 nb_streams:4
Jan 08, 2019 04:08:18.775 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:18.775 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:18.776 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:18.776 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:18.776 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:18.776 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:18.776 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:18.780 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:18.782 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:18.786 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:18.788 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:18.796 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:18.805 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:18.813 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:18.817 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:18.823 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:19.270 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:19.277 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:19.737 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 133277 bytes read:222589 seeks:2 frames:76
Jan 08, 2019 04:08:19.737 [0x7fd1a5d9e840] DEBUG - [MI]  * 6af1012a778955573f9e3ced3a36da47c4afa652 [mkv h264/dca] Duration: 6590464ms, 1804x1080 (1.670370) 23.976fps, bitrate=24104kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv")
Jan 08, 2019 04:08:19.739 [0x7fd1a5d9e840] DEBUG - [MI] Importing 32 media chapters.
Jan 08, 2019 04:08:19.739 [0x7fd1a5d9e840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:19.747 [0x7fd1a5d9e840] DEBUG - Doing expensive tags write for 'Dr. No' because something changed.
Jan 08, 2019 04:08:19.765 [0x7fd1a5d9e840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv"
Jan 08, 2019 04:08:19.768 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Statistics: 222589 bytes read, 2 seeks
Jan 08, 2019 04:08:19.769 [0x7fd1a5d9e840] DEBUG - Streams didn't change in analysis, not saving.
Jan 08, 2019 04:08:19.769 [0x7fd1a5d9e840] DEBUG - Updating media item 22585, size=19857222052, metadata_item_id=10125
Jan 08, 2019 04:08:19.770 [0x7fd1a5d9e840] DEBUG - Updating part with ID=29889 [/mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv]
Jan 08, 2019 04:08:19.772 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10125&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:19.774 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10125&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:19.774 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10125&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:19.776 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10125&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:19.776 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10125&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:19.778 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10125&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:19.790 [0x7fd1a5d9e840] DEBUG - Analyzing media parts for item 8908 (Monty Python and the Holy Grail): 14899
Jan 08, 2019 04:08:19.790 [0x7fd1a5d9e840] DEBUG - [ID 21927] Media part analysis: /mnt/nfs/media/Videos/Movies/Monty Python And The Holy Grail (1975).mkv
Jan 08, 2019 04:08:19.790 [0x7fd1a5d9e840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Monty Python And The Holy Grail (1975).mkv"
Jan 08, 2019 04:08:19.790 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Monty Python And The Holy Grail (1975).mkv' for reading
Jan 08, 2019 04:08:19.790 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:19.820 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:19.832 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:19.832 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:19.832 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 787 bytes read:57378 seeks:2 nb_streams:2
Jan 08, 2019 04:08:19.832 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:19.832 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:19.833 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:19.833 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:19.834 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:19.834 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:19.834 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:19.835 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:19.836 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:19.837 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:19.837 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:19.839 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:19.840 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:19.841 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:19.843 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:19.844 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:19.845 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:19.846 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:19.847 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 18242 bytes read:90146 seeks:2 frames:19
Jan 08, 2019 04:08:19.847 [0x7fd1a5d9e840] DEBUG - [MI]  * 3576f11e0f7d1c3802d747f8bff04293d9f9cfa3 [mkv h264/ac3] Duration: 5528690ms, 714x472 (1.792373) 23.976fps, bitrate=3123kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Monty Python And The Holy Grail (1975).mkv")
Jan 08, 2019 04:08:19.848 [0x7fd1a5d9e840] DEBUG - [MI] Importing 28 media chapters.
Jan 08, 2019 04:08:19.848 [0x7fd1a5d9e840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:19.853 [0x7fd1a5d9e840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Monty Python And The Holy Grail (1975).mkv"
Jan 08, 2019 04:08:19.854 [0x7fd1a5d9e840] DEBUG - [FFMPEG] - Statistics: 90146 bytes read, 2 seeks
Jan 08, 2019 04:08:19.855 [0x7fd1a5d9e840] DEBUG - Updating media item 14899, size=2158587569, metadata_item_id=8908
Jan 08, 2019 04:08:19.856 [0x7fd1a5d9e840] DEBUG - Updating part with ID=21927 [/mnt/nfs/media/Videos/Movies/Monty Python And The Holy Grail (1975).mkv]
Jan 08, 2019 04:08:19.858 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8908&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:19.860 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8908&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:19.861 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8908&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:19.863 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8908&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:19.863 [0x7fd18f7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8908&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:19.865 [0x7fd18f7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8908&metadataType=1&state=-1&mediaState=idle
