Sep 02, 2018 04:51:07.754 [0x7f9b32256700] INFO - Plex Media Scanner v1.13.5.5332-21ab172de - ubuntu PC x86_64 - build: linux-ubuntu-x86_64 - GMT -05:00
Sep 02, 2018 04:51:07.754 [0x7f9b32256700] INFO - Linux version: 4.4.59+ (#23739 SMP PREEMPT Tue Jul 10 00:18:17 CST 2018), language: en-US
Sep 02, 2018 04:51:07.754 [0x7f9b32256700] INFO - Processor Intel(R) Celeron(R) CPU J3455 @ 1.50GHz
Sep 02, 2018 04:51:07.754 [0x7f9b32256700] INFO - /volume1/@appstore/Plex Media Server/Plex Media Scanner --analyze --item 10,131,4441,5,3405,3266,2991,2992,3621,3550 --log-file-suffix  Analysis
Sep 02, 2018 04:51:07.773 [0x7f9b3e74b800] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Sep 02, 2018 04:51:08.071 [0x7f9b3e74b800] DEBUG - Analyzing media parts for item 10 (Wizards): 10
Sep 02, 2018 04:51:08.071 [0x7f9b3e74b800] DEBUG - [ID 10] Media part analysis: /volume1/Plex/Library/Shared Videos/Movies/Wizards/title00.mkv
Sep 02, 2018 04:51:08.109 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/Movies/Wizards/title00.mkv"
Sep 02, 2018 04:51:08.110 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/Movies/Wizards/title00.mkv' for reading
Sep 02, 2018 04:51:08.110 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:08.130 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:51:08.139 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:08.139 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:08.139 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:08.139 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 3875 bytes read:34000 seeks:2 nb_streams:3
Sep 02, 2018 04:51:08.142 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:08.143 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:08.143 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 16671 bytes read:66768 seeks:2 frames:8
Sep 02, 2018 04:51:08.145 [0x7f9b3e74b800] DEBUG - [MI]  * 3c2c31cbc6af5566b64dcf49577628f8969742ba [mkv mpeg2video/ac3] Duration: 4856484ms, 720x480 (1.777070) 29.970fps, bitrate=8924kbps, audio channels=2, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/Movies/Wizards/title00.mkv")
Sep 02, 2018 04:51:08.146 [0x7f9b3e74b800] DEBUG - [MI] Importing 20 media chapters.
Sep 02, 2018 04:51:08.147 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:08.152 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/Movies/Wizards/title00.mkv"
Sep 02, 2018 04:51:08.152 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 66768 bytes read, 2 seeks
Sep 02, 2018 04:51:08.156 [0x7f9b3e74b800] DEBUG - Updating media item 10, size=5417666971, metadata_item_id=10
Sep 02, 2018 04:51:08.157 [0x7f9b3e74b800] DEBUG - Updating part with ID=10 [/volume1/Plex/Library/Shared Videos/Movies/Wizards/title00.mkv]
Sep 02, 2018 04:51:08.278 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=10&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:08.281 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=10&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:08.282 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=10&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:08.284 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=10&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:08.285 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=10&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:51:08.287 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=10&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:51:08.305 [0x7f9b3e74b800] DEBUG - Analyzing media parts for item 131 (Deepwater Horizon): 143
Sep 02, 2018 04:51:08.305 [0x7f9b3e74b800] DEBUG - [ID 143] Media part analysis: /volume1/Plex/Library/Shared Videos/Movies/Deepwater Horizon/Deepwater.Horizon.2016.1080p.BluRay.DD5.1.x264.mkv
Sep 02, 2018 04:51:08.305 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/Movies/Deepwater Horizon/Deepwater.Horizon.2016.1080p.BluRay.DD5.1.x264.mkv"
Sep 02, 2018 04:51:08.305 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/Movies/Deepwater Horizon/Deepwater.Horizon.2016.1080p.BluRay.DD5.1.x264.mkv' for reading
Sep 02, 2018 04:51:08.306 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:08.359 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:51:08.367 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:08.368 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:08.368 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:08.368 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6539 bytes read:34019 seeks:2 nb_streams:3
Sep 02, 2018 04:51:08.368 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:08.369 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:08.370 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:08.370 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:08.371 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:08.371 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:08.371 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:51:08.371 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:51:08.371 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:08.374 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:08.377 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:08.379 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:08.382 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:08.390 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:08.397 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:08.403 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:08.408 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:08.414 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:08.421 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:08.427 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:08.427 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 50043 bytes read:99555 seeks:2 frames:26
Sep 02, 2018 04:51:08.427 [0x7f9b3e74b800] DEBUG - [MI]  * 94a0dac123f0a33fb3b7f029fd222c0569dd6c57 [mkv h264/ac3] Duration: 6437473ms, 1920x800 (2.400000) 23.976fps, bitrate=20295kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/Movies/Deepwater Horizon/Deepwater.Horizon.2016.1080p.BluRay.DD5.1.x264.mkv")
Sep 02, 2018 04:51:08.428 [0x7f9b3e74b800] DEBUG - [MI] Importing 16 media chapters.
Sep 02, 2018 04:51:08.429 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:08.431 [0x7f9b3e74b800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:08.432 [0x7f9b3e74b800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:08.434 [0x7f9b3e74b800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:08.435 [0x7f9b3e74b800] DEBUG - Updating metadata item (save) (Deepwater Horizon) with ID 131
Sep 02, 2018 04:51:08.535 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/Movies/Deepwater Horizon/Deepwater.Horizon.2016.1080p.BluRay.DD5.1.x264.mkv"
Sep 02, 2018 04:51:08.538 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 99555 bytes read, 2 seeks
Sep 02, 2018 04:51:08.540 [0x7f9b3e74b800] DEBUG - Updating media item 143, size=16331860852, metadata_item_id=131
Sep 02, 2018 04:51:08.541 [0x7f9b3e74b800] DEBUG - Updating part with ID=143 [/volume1/Plex/Library/Shared Videos/Movies/Deepwater Horizon/Deepwater.Horizon.2016.1080p.BluRay.DD5.1.x264.mkv]
Sep 02, 2018 04:51:08.617 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=131&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:08.621 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=131&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:08.621 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=131&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:08.623 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=131&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:08.624 [0x7f9b3e74b800] DEBUG - Analyzing media parts for item 4441 (Chung): 8398
Sep 02, 2018 04:51:08.624 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=131&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:51:08.626 [0x7f9b3e74b800] DEBUG - [ID 8406] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Veep/Season 1/Veep.S01E04.Chung.720p.BluRay.x264-DEMAND.mkv
Sep 02, 2018 04:51:08.626 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Veep/Season 1/Veep.S01E04.Chung.720p.BluRay.x264-DEMAND.mkv"
Sep 02, 2018 04:51:08.626 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Veep/Season 1/Veep.S01E04.Chung.720p.BluRay.x264-DEMAND.mkv' for reading
Sep 02, 2018 04:51:08.626 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:08.627 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=131&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:51:08.670 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:51:08.671 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:08.671 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:08.671 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:08.671 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6087 bytes read:32768 seeks:0 nb_streams:3
Sep 02, 2018 04:51:08.671 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:08.671 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:08.672 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:08.672 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:08.673 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:51:08.673 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:51:08.673 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:08.675 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:08.677 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:08.679 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:08.681 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:08.685 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:08.689 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:08.694 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:08.696 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:08.699 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:08.703 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:08.708 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:08.708 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 104087 bytes read:131072 seeks:0 frames:58
Sep 02, 2018 04:51:08.708 [0x7f9b3e74b800] DEBUG - [MI]  * f95bbe83c6d39b5dba2482c9d5e34d464fc54e4e [mkv h264/dca] Duration: 1691659ms, 1280x720 (1.777778) 23.976fps, bitrate=7393kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Veep/Season 1/Veep.S01E04.Chung.720p.BluRay.x264-DEMAND.mkv")
Sep 02, 2018 04:51:08.709 [0x7f9b3e74b800] DEBUG - [MI] Importing 6 media chapters.
Sep 02, 2018 04:51:08.709 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:08.711 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Veep/Season 1/Veep.S01E04.Chung.720p.BluRay.x264-DEMAND.mkv"
Sep 02, 2018 04:51:08.714 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 131072 bytes read, 0 seeks
Sep 02, 2018 04:51:08.715 [0x7f9b3e74b800] DEBUG - Committing media stream changes for part 8406
Sep 02, 2018 04:51:08.794 [0x7f9b3e74b800] DEBUG - Updating media item 8398, size=1563431867, metadata_item_id=4441
Sep 02, 2018 04:51:08.795 [0x7f9b3e74b800] DEBUG - Updating part with ID=8406 [/volume1/Plex/Library/Shared Videos/TV/Veep/Season 1/Veep.S01E04.Chung.720p.BluRay.x264-DEMAND.mkv]
Sep 02, 2018 04:51:08.895 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4441&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:08.897 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4441&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:08.897 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4441&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:08.899 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4441&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:08.900 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4441&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:08.902 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4441&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:08.910 [0x7f9b3e74b800] DEBUG - Analyzing media parts for item 5 (Iron Sky): 5
Sep 02, 2018 04:51:08.910 [0x7f9b3e74b800] DEBUG - [ID 5] Media part analysis: /volume1/Plex/Library/Shared Videos/Movies/Iron Sky/Iron Sky (2012) [1080p]/Iron.Sky.2012.1080p.BluRay.x264.YIFY.mp4
Sep 02, 2018 04:51:08.915 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/Movies/Iron Sky/Iron Sky (2012) [1080p]/Iron.Sky.2012.1080p.BluRay.x264.YIFY.mp4"
Sep 02, 2018 04:51:08.915 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/Movies/Iron Sky/Iron Sky (2012) [1080p]/Iron.Sky.2012.1080p.BluRay.x264.YIFY.mp4' for reading
Sep 02, 2018 04:51:08.916 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:08.959 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Sep 02, 2018 04:51:08.959 [0x7f9b3e74b800] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Sep 02, 2018 04:51:08.959 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:09.037 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Sep 02, 2018 04:51:09.038 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:09.067 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1396571386 bytes read:2736042 seeks:1 nb_streams:2
Sep 02, 2018 04:51:09.067 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:09.068 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:09.072 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:51:09.072 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:51:09.072 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:09.075 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:09.078 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.080 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:09.084 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.090 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.096 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.102 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.109 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.115 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.122 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.131 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:09.131 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2783448 bytes read:2834346 seeks:2 frames:14
Sep 02, 2018 04:51:09.131 [0x7f9b3e74b800] DEBUG - [MI] Opening "/volume1/Plex/Library/Shared Videos/Movies/Iron Sky/Iron Sky (2012) [1080p]/Iron.Sky.2012.1080p.BluRay.x264.YIFY.mp4" for MP4 atom parsing
Sep 02, 2018 04:51:09.131 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 24 at offset 0
Sep 02, 2018 04:51:09.131 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found moov atom of size 2715999 at offset 24
Sep 02, 2018 04:51:09.134 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 1713197 at offset 161
Sep 02, 2018 04:51:09.134 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 1002665 at offset 1713358
Sep 02, 2018 04:51:09.134 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 1713097 at offset 261
Sep 02, 2018 04:51:09.134 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 1713024 at offset 334
Sep 02, 2018 04:51:09.134 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 1712960 at offset 398
Sep 02, 2018 04:51:09.134 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 1002565 at offset 1713458
Sep 02, 2018 04:51:09.134 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 1002470 at offset 1713553
Sep 02, 2018 04:51:09.134 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 1002410 at offset 1713613
Sep 02, 2018 04:51:09.134 [0x7f9b3e74b800] DEBUG - [MI]  * 0ffb71defecbb4616f3599fe4b57c0c660f47d7d [mp4 h264/aac] Duration: 5553283ms, 1920x816 (2.352941) 24.000fps, bitrate=2011kbps, audio channels=2, optimizedForStreaming=1 ("/volume1/Plex/Library/Shared Videos/Movies/Iron Sky/Iron Sky (2012) [1080p]/Iron.Sky.2012.1080p.BluRay.x264.YIFY.mp4")
Sep 02, 2018 04:51:09.136 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:09.136 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/Movies/Iron Sky/Iron Sky (2012) [1080p]/Iron.Sky.2012.1080p.BluRay.x264.YIFY.mp4"
Sep 02, 2018 04:51:09.140 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 2834346 bytes read, 2 seeks
Sep 02, 2018 04:51:09.142 [0x7f9b3e74b800] DEBUG - Committing media stream changes for part 5
Sep 02, 2018 04:51:09.254 [0x7f9b3e74b800] DEBUG - Updating media item 5, size=1396571417, metadata_item_id=5
Sep 02, 2018 04:51:09.255 [0x7f9b3e74b800] DEBUG - Updating part with ID=5 [/volume1/Plex/Library/Shared Videos/Movies/Iron Sky/Iron Sky (2012) [1080p]/Iron.Sky.2012.1080p.BluRay.x264.YIFY.mp4]
Sep 02, 2018 04:51:09.333 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:09.335 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:09.335 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:09.337 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:09.338 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:51:09.339 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:51:09.340 [0x7f9b3e74b800] DEBUG - Analyzing media parts for item 3405 (The Things We Bury): 7082,7104
Sep 02, 2018 04:51:09.342 [0x7f9b3e74b800] DEBUG - [ID 7082] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward.mkv
Sep 02, 2018 04:51:09.343 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward.mkv"
Sep 02, 2018 04:51:09.343 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward.mkv' for reading
Sep 02, 2018 04:51:09.343 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:09.381 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:51:09.393 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:09.393 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:09.393 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:09.393 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:09.393 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6025 bytes read:34290 seeks:2 nb_streams:4
Sep 02, 2018 04:51:09.394 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:09.394 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:09.395 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:09.395 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:09.395 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:51:09.395 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:51:09.395 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:09.397 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:09.400 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.401 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:09.403 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.407 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:09.411 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:09.415 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.417 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.420 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:09.423 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:09.428 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:09.428 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 104032 bytes read:132594 seeks:2 frames:58
Sep 02, 2018 04:51:09.428 [0x7f9b3e74b800] DEBUG - [MI]  * 9f14deb335e392d2500982ac1cc02ef9e61a85b8 [mkv h264/dca] Duration: 2583168ms, 1280x720 (1.777778) 23.976fps, bitrate=7257kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward.mkv")
Sep 02, 2018 04:51:09.429 [0x7f9b3e74b800] DEBUG - [MI] Importing 6 media chapters.
Sep 02, 2018 04:51:09.430 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:09.432 [0x7f9b3e74b800] DEBUG - Doing expensive tags write for 'The Things We Bury' because something changed.
Sep 02, 2018 04:51:09.583 [0x7f9b3e74b800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:09.583 [0x7f9b3e74b800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:09.585 [0x7f9b3e74b800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:09.586 [0x7f9b3e74b800] DEBUG - Updating metadata item (save) (The Things We Bury) with ID 3405
Sep 02, 2018 04:51:09.727 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward.mkv"
Sep 02, 2018 04:51:09.727 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Sep 02, 2018 04:51:09.729 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Sep 02, 2018 04:51:09.729 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 132594 bytes read, 2 seeks
Sep 02, 2018 04:51:09.730 [0x7f9b3e74b800] DEBUG - Streams didn't change in analysis, not saving.
Sep 02, 2018 04:51:09.730 [0x7f9b3e74b800] DEBUG - Updating media item 7082, size=2343544873, metadata_item_id=3405
Sep 02, 2018 04:51:09.731 [0x7f9b3e74b800] DEBUG - Updating part with ID=7082 [/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward.mkv]
Sep 02, 2018 04:51:09.839 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:09.840 [0x7f9b3e74b800] DEBUG - [ID 7104] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/ipad/Marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward-8.m4v
Sep 02, 2018 04:51:09.840 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/ipad/Marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward-8.m4v"
Sep 02, 2018 04:51:09.840 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/ipad/Marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward-8.m4v' for reading
Sep 02, 2018 04:51:09.840 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:09.841 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:09.842 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:09.843 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:09.844 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:09.846 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:09.877 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Sep 02, 2018 04:51:09.877 [0x7f9b3e74b800] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Sep 02, 2018 04:51:09.887 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:09.911 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 7470, duration: 232484760
Sep 02, 2018 04:51:09.916 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Offset DTS by 7470 to make first pts zero.
Sep 02, 2018 04:51:09.928 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Sep 02, 2018 04:51:09.928 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:09.942 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 0, duration: 123991056
Sep 02, 2018 04:51:09.951 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:09.951 [0x7f9b3e74b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.005008
Sep 02, 2018 04:51:09.951 [0x7f9b3e74b800] DEBUG - [FFMPEG] - rfps: 23.916667 0.012325
Sep 02, 2018 04:51:09.951 [0x7f9b3e74b800] DEBUG - [FFMPEG] - rfps: 24.000000 0.000828
Sep 02, 2018 04:51:09.951 [0x7f9b3e74b800] DEBUG - [FFMPEG] - rfps: 24.083333 0.015982
Sep 02, 2018 04:51:09.951 [0x7f9b3e74b800] DEBUG - [FFMPEG] - rfps: 48.000000 0.003312
Sep 02, 2018 04:51:09.952 [0x7f9b3e74b800] DEBUG - [FFMPEG] - rfps: 23.976024 0.000050
Sep 02, 2018 04:51:09.952 [0x7f9b3e74b800] DEBUG - [FFMPEG] - rfps: 47.952048 0.000198
Sep 02, 2018 04:51:09.952 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 647080338 bytes read:2259074 seeks:3 nb_streams:3
Sep 02, 2018 04:51:09.952 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:09.952 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:09.954 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:51:09.954 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:51:09.954 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:09.955 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:09.957 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:09.958 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:09.960 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:09.960 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 1058 bytes read:2291842 seeks:4 frames:3
Sep 02, 2018 04:51:09.960 [0x7f9b3e74b800] DEBUG - [MI] Opening "/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/ipad/Marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward-8.m4v" for MP4 atom parsing
Sep 02, 2018 04:51:09.960 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Sep 02, 2018 04:51:09.960 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Sep 02, 2018 04:51:09.960 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping mdat atom of size 644886760 at offset 40
Sep 02, 2018 04:51:09.960 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found moov atom of size 2193538 at offset 644886800
Sep 02, 2018 04:51:09.962 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 1223588 at offset 644886940
Sep 02, 2018 04:51:09.962 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 969199 at offset 646110528
Sep 02, 2018 04:51:09.962 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 499 at offset 647079727
Sep 02, 2018 04:51:09.962 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 1223432 at offset 644887096
Sep 02, 2018 04:51:09.962 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 1223347 at offset 644887181
Sep 02, 2018 04:51:09.962 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 1223283 at offset 644887245
Sep 02, 2018 04:51:09.962 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 644887076
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 644887084
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 969021 at offset 646110684
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 968942 at offset 646110763
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 968882 at offset 646110823
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 646110664
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 646110672
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 399 at offset 647079827
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 311 at offset 647079915
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 235 at offset 647079991
Sep 02, 2018 04:51:09.963 [0x7f9b3e74b800] DEBUG - [MI]  * a88825f6d7ff53cf1a4c269334ce01fde976a286 [mp4 h264/aac] Duration: 2583164ms, 1104x720 (1.533333) 23.976fps, bitrate=2003kbps, audio channels=2, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/ipad/Marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward-8.m4v")
Sep 02, 2018 04:51:09.964 [0x7f9b3e74b800] DEBUG - [MI] Importing 6 media chapters.
Sep 02, 2018 04:51:09.965 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:09.967 [0x7f9b3e74b800] DEBUG - Doing expensive tags write for 'The Things We Bury' because something changed.
Sep 02, 2018 04:51:10.165 [0x7f9b3e74b800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:10.165 [0x7f9b3e74b800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:10.168 [0x7f9b3e74b800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:10.168 [0x7f9b3e74b800] DEBUG - Updating metadata item (save) (The Things We Bury) with ID 3405
Sep 02, 2018 04:51:10.265 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/ipad/Marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward-8.m4v"
Sep 02, 2018 04:51:10.265 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Sep 02, 2018 04:51:10.267 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 2291842 bytes read, 4 seeks
Sep 02, 2018 04:51:10.267 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Sep 02, 2018 04:51:10.268 [0x7f9b3e74b800] DEBUG - Committing media stream changes for part 7104
Sep 02, 2018 04:51:10.332 [0x7f9b3e74b800] DEBUG - Updating media item 7104, size=647080338, metadata_item_id=3405
Sep 02, 2018 04:51:10.333 [0x7f9b3e74b800] DEBUG - Updating part with ID=7104 [/volume1/Plex/Library/Shared Videos/TV/Marvel's Agents of S.H.I.E.L.D/Season 2/ipad/Marvels.agents.of.s.h.i.e.l.d.s02e08.720p.bluray.x264-reward-8.m4v]
Sep 02, 2018 04:51:10.399 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:10.401 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:10.402 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:10.404 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:10.404 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:10.406 [0x7f9b3e74b800] DEBUG - Analyzing media parts for item 3266 (Allegory of Love): 6954
Sep 02, 2018 04:51:10.407 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3405&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:10.408 [0x7f9b3e74b800] DEBUG - [ID 6954] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S03/Lewis S03E01 Allegary of Love x264 RB58.mp4
Sep 02, 2018 04:51:10.408 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S03/Lewis S03E01 Allegary of Love x264 RB58.mp4"
Sep 02, 2018 04:51:10.408 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S03/Lewis S03E01 Allegary of Love x264 RB58.mp4' for reading
Sep 02, 2018 04:51:10.408 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:10.425 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Sep 02, 2018 04:51:10.426 [0x7f9b3e74b800] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Sep 02, 2018 04:51:10.437 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:10.483 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 14400, duration: 500493600
Sep 02, 2018 04:51:10.499 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Offset DTS by 14400 to make first pts zero.
Sep 02, 2018 04:51:10.524 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Sep 02, 2018 04:51:10.525 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:10.544 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:10.544 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Processing st: 2, edit list 0 - media time: 14400, duration: 500493600
Sep 02, 2018 04:51:10.544 [0x7f9b3e74b800] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Sep 02, 2018 04:51:10.602 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 739779850 bytes read:3205681 seeks:11 nb_streams:3
Sep 02, 2018 04:51:10.603 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:10.603 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:10.604 [0x7f9b3e74b800] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Sep 02, 2018 04:51:10.605 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:51:10.605 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:51:10.605 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:10.606 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:10.607 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:10.607 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2971 bytes read:3238449 seeks:12 frames:9
Sep 02, 2018 04:51:10.607 [0x7f9b3e74b800] DEBUG - [MI] Opening "/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S03/Lewis S03E01 Allegary of Love x264 RB58.mp4" for MP4 atom parsing
Sep 02, 2018 04:51:10.607 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Sep 02, 2018 04:51:10.607 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Sep 02, 2018 04:51:10.607 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping mdat atom of size 736731411 at offset 160
Sep 02, 2018 04:51:10.607 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found moov atom of size 3048255 at offset 736731571
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 1873914 at offset 736731711
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 1173502 at offset 738605625
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 588 at offset 739779127
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 1873758 at offset 736731811
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 1873685 at offset 736731884
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 1873621 at offset 736731948
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 738605569
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 738605577
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 1173380 at offset 738605725
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 1173307 at offset 738605798
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 1173247 at offset 738605858
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 452 at offset 739779227
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 379 at offset 739779300
Sep 02, 2018 04:51:10.610 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 259 at offset 739779420
Sep 02, 2018 04:51:10.611 [0x7f9b3e74b800] DEBUG - [MI]  * 5dcbbf404d6c444d4da1cfe6ddd7b37f75689992 [mp4 h264/aac] Duration: 5561045ms, 716x576 (1.767901) 24.999fps, bitrate=1064kbps, audio channels=2, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S03/Lewis S03E01 Allegary of Love x264 RB58.mp4")
Sep 02, 2018 04:51:10.612 [0x7f9b3e74b800] DEBUG - [MI] Importing 8 media chapters.
Sep 02, 2018 04:51:10.612 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:10.615 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S03/Lewis S03E01 Allegary of Love x264 RB58.mp4"
Sep 02, 2018 04:51:10.616 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 3238449 bytes read, 12 seeks
Sep 02, 2018 04:51:10.618 [0x7f9b3e74b800] DEBUG - Committing media stream changes for part 6954
Sep 02, 2018 04:51:10.732 [0x7f9b3e74b800] DEBUG - Updating media item 6954, size=739779958, metadata_item_id=3266
Sep 02, 2018 04:51:10.733 [0x7f9b3e74b800] DEBUG - Updating part with ID=6954 [/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S03/Lewis S03E01 Allegary of Love x264 RB58.mp4]
Sep 02, 2018 04:51:10.806 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3266&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:10.808 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3266&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:10.809 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3266&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:10.811 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3266&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:10.811 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3266&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:10.813 [0x7f9b3e74b800] DEBUG - Analyzing media parts for item 2991 (Tooth and Claw): 6695
Sep 02, 2018 04:51:10.813 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3266&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:10.815 [0x7f9b3e74b800] DEBUG - [ID 6695] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E02 - Tooth and Claw.mkv
Sep 02, 2018 04:51:10.815 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E02 - Tooth and Claw.mkv"
Sep 02, 2018 04:51:10.815 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E02 - Tooth and Claw.mkv' for reading
Sep 02, 2018 04:51:10.815 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:10.833 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:51:10.833 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:10.833 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:10.833 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5066 bytes read:32768 seeks:0 nb_streams:2
Sep 02, 2018 04:51:10.834 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:10.834 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:10.834 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:10.834 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:10.835 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:51:10.835 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:51:10.835 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:10.839 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:10.843 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:10.846 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:10.850 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:10.858 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:10.867 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:10.876 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:10.881 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:10.887 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:10.893 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:10.900 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:10.900 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 103552 bytes read:131072 seeks:0 frames:58
Sep 02, 2018 04:51:10.900 [0x7f9b3e74b800] DEBUG - [MI]  * 1b402c5d5dfa66b5e8f2394d4604239495eb0e09 [mkv h264/dca] Duration: 2804594ms, 1872x1080 (1.733333) 23.976fps, bitrate=10036kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E02 - Tooth and Claw.mkv")
Sep 02, 2018 04:51:10.902 [0x7f9b3e74b800] DEBUG - [MI] Importing 13 media chapters.
Sep 02, 2018 04:51:10.902 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:10.905 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E02 - Tooth and Claw.mkv"
Sep 02, 2018 04:51:10.908 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 131072 bytes read, 0 seeks
Sep 02, 2018 04:51:10.908 [0x7f9b3e74b800] DEBUG - Streams didn't change in analysis, not saving.
Sep 02, 2018 04:51:10.909 [0x7f9b3e74b800] DEBUG - Updating media item 6695, size=3518533682, metadata_item_id=2991
Sep 02, 2018 04:51:10.909 [0x7f9b3e74b800] DEBUG - Updating part with ID=6695 [/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E02 - Tooth and Claw.mkv]
Sep 02, 2018 04:51:10.984 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2991&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:10.986 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2991&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:10.986 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2991&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:10.988 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2991&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:10.989 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2991&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:10.990 [0x7f9b3e74b800] DEBUG - Analyzing media parts for item 2992 (School Reunion): 6696
Sep 02, 2018 04:51:10.991 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2991&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:10.992 [0x7f9b3e74b800] DEBUG - [ID 6696] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E03 - School Reunion.mkv
Sep 02, 2018 04:51:10.992 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E03 - School Reunion.mkv"
Sep 02, 2018 04:51:10.992 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E03 - School Reunion.mkv' for reading
Sep 02, 2018 04:51:10.992 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:11.047 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:51:11.047 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:11.047 [0x7f9b3e74b800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:51:11.047 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5066 bytes read:32768 seeks:0 nb_streams:2
Sep 02, 2018 04:51:11.048 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:11.048 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:11.048 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:11.048 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:11.049 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:51:11.049 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:51:11.049 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:11.052 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:11.057 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:11.059 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:11.063 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:11.072 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:11.080 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:11.090 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:11.094 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:11.100 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:11.107 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:51:11.114 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:11.114 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 103551 bytes read:131072 seeks:0 frames:58
Sep 02, 2018 04:51:11.114 [0x7f9b3e74b800] DEBUG - [MI]  * c72847189ef535d275d2d3c9f14e2ec7f94334d3 [mkv h264/dca] Duration: 2784365ms, 1872x1080 (1.733333) 23.976fps, bitrate=10111kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E03 - School Reunion.mkv")
Sep 02, 2018 04:51:11.115 [0x7f9b3e74b800] DEBUG - [MI] Importing 13 media chapters.
Sep 02, 2018 04:51:11.116 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:11.119 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E03 - School Reunion.mkv"
Sep 02, 2018 04:51:11.122 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 131072 bytes read, 0 seeks
Sep 02, 2018 04:51:11.124 [0x7f9b3e74b800] DEBUG - Updating media item 6696, size=3519209558, metadata_item_id=2992
Sep 02, 2018 04:51:11.124 [0x7f9b3e74b800] DEBUG - Updating part with ID=6696 [/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 2/Doctor Who (2005) - S02E03 - School Reunion.mkv]
Sep 02, 2018 04:51:11.195 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2992&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:11.197 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2992&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:11.197 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2992&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:11.199 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2992&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:11.200 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2992&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:11.201 [0x7f9b3e74b800] DEBUG - Analyzing media parts for item 3621 (Episode 29): 7330
Sep 02, 2018 04:51:11.203 [0x7f9b3e74b800] DEBUG - [ID 7330] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Pink Panther/4/Video Ts-29.m4v
Sep 02, 2018 04:51:11.203 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Pink Panther/4/Video Ts-29.m4v"
Sep 02, 2018 04:51:11.203 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=2992&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:11.203 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Pink Panther/4/Video Ts-29.m4v' for reading
Sep 02, 2018 04:51:11.203 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:11.241 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Sep 02, 2018 04:51:11.241 [0x7f9b3e74b800] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Sep 02, 2018 04:51:11.260 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:11.266 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 7200, duration: 13597200
Sep 02, 2018 04:51:11.266 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Offset DTS by 7200 to make first pts zero.
Sep 02, 2018 04:51:11.267 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Sep 02, 2018 04:51:11.267 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:11.268 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 0, duration: 7200768
Sep 02, 2018 04:51:11.269 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:11.269 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 29175380 bytes read:182479 seeks:3 nb_streams:3
Sep 02, 2018 04:51:11.269 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:11.269 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:11.271 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:51:11.271 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:51:11.271 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:11.272 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:11.273 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:11.273 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:11.274 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:11.274 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 971 bytes read:215247 seeks:4 frames:3
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - [MI] Opening "/volume1/Plex/Library/Shared Videos/TV/Pink Panther/4/Video Ts-29.m4v" for MP4 atom parsing
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping mdat atom of size 29058397 at offset 40
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found moov atom of size 116943 at offset 29058437
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 59477 at offset 29058577
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 56775 at offset 29118054
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 439 at offset 29174829
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 59321 at offset 29058733
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 59236 at offset 29058818
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 59172 at offset 29058882
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 29058713
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 29058721
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 56597 at offset 29118210
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 56518 at offset 29118289
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 56458 at offset 29118349
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 29118190
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 29118198
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Sep 02, 2018 04:51:11.275 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 339 at offset 29174929
Sep 02, 2018 04:51:11.276 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 251 at offset 29175017
Sep 02, 2018 04:51:11.276 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 175 at offset 29175093
Sep 02, 2018 04:51:11.276 [0x7f9b3e74b800] DEBUG - [MI]  * 3019929b163ecd022b4e0c774a12809421e58ac2 [mp4 h264/aac] Duration: 151080ms, 720x476 (2.149735) 24.993fps, bitrate=1544kbps, audio channels=2, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Pink Panther/4/Video Ts-29.m4v")
Sep 02, 2018 04:51:11.277 [0x7f9b3e74b800] DEBUG - [MI] Importing 1 media chapters.
Sep 02, 2018 04:51:11.277 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:11.278 [0x7f9b3e74b800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:11.278 [0x7f9b3e74b800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:11.280 [0x7f9b3e74b800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:11.281 [0x7f9b3e74b800] DEBUG - Updating metadata item (save) () with ID 3621
Sep 02, 2018 04:51:11.349 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Sep 02, 2018 04:51:11.350 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Pink Panther/4/Video Ts-29.m4v"
Sep 02, 2018 04:51:11.351 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Sep 02, 2018 04:51:11.351 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 215247 bytes read, 4 seeks
Sep 02, 2018 04:51:11.353 [0x7f9b3e74b800] DEBUG - Committing media stream changes for part 7330
Sep 02, 2018 04:51:11.405 [0x7f9b3e74b800] DEBUG - Updating media item 7330, size=29175380, metadata_item_id=3621
Sep 02, 2018 04:51:11.406 [0x7f9b3e74b800] DEBUG - Updating part with ID=7330 [/volume1/Plex/Library/Shared Videos/TV/Pink Panther/4/Video Ts-29.m4v]
Sep 02, 2018 04:51:11.506 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3621&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:11.508 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3621&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:11.509 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3621&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:11.511 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3621&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:11.511 [0x7f9b3e74b800] DEBUG - Analyzing media parts for item 3550 (Pinky...He Delivers): 7262
Sep 02, 2018 04:51:11.511 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3621&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:11.513 [0x7f9b3e74b800] DEBUG - [ID 7262] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Pink Panther/1/Video Ts-33.m4v
Sep 02, 2018 04:51:11.513 [0x7f9b3e74b800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Pink Panther/1/Video Ts-33.m4v"
Sep 02, 2018 04:51:11.513 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Pink Panther/1/Video Ts-33.m4v' for reading
Sep 02, 2018 04:51:11.513 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:51:11.514 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3621&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:11.531 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Sep 02, 2018 04:51:11.531 [0x7f9b3e74b800] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Sep 02, 2018 04:51:11.562 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:11.577 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 7200, duration: 32670000
Sep 02, 2018 04:51:11.578 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Offset DTS by 7200 to make first pts zero.
Sep 02, 2018 04:51:11.579 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Sep 02, 2018 04:51:11.580 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:11.598 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 0, duration: 17421312
Sep 02, 2018 04:51:11.599 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:51:11.599 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 131481957 bytes read:339022 seeks:3 nb_streams:3
Sep 02, 2018 04:51:11.600 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:51:11.600 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:51:11.602 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:51:11.602 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:51:11.602 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:51:11.602 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:11.603 [0x7f9b3e74b800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:51:11.604 [0x7f9b3e74b800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:51:11.605 [0x7f9b3e74b800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:51:11.605 [0x7f9b3e74b800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 986 bytes read:371790 seeks:4 frames:3
Sep 02, 2018 04:51:11.605 [0x7f9b3e74b800] DEBUG - [MI] Opening "/volume1/Plex/Library/Shared Videos/TV/Pink Panther/1/Video Ts-33.m4v" for MP4 atom parsing
Sep 02, 2018 04:51:11.605 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Sep 02, 2018 04:51:11.605 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Sep 02, 2018 04:51:11.605 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Skipping mdat atom of size 131208431 at offset 40
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found moov atom of size 273486 at offset 131208471
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 136172 at offset 131208611
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 136623 at offset 131344783
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found trak atom of size 439 at offset 131481406
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 136016 at offset 131208767
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 135931 at offset 131208852
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 135867 at offset 131208916
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 131208747
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 131208755
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 136445 at offset 131344939
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 136366 at offset 131345018
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 136306 at offset 131345078
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 131344919
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 131344927
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found mdia atom of size 339 at offset 131481506
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found minf atom of size 251 at offset 131481594
Sep 02, 2018 04:51:11.606 [0x7f9b3e74b800] DEBUG - Mp4AtomParser: Found stbl atom of size 175 at offset 131481670
Sep 02, 2018 04:51:11.607 [0x7f9b3e74b800] DEBUG - [MI]  * f1cdb66611373211215b535c4a8820ace1633f22 [mp4 h264/aac] Duration: 363000ms, 720x576 (1.777778) 25.000fps, bitrate=2897kbps, audio channels=2, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Pink Panther/1/Video Ts-33.m4v")
Sep 02, 2018 04:51:11.608 [0x7f9b3e74b800] DEBUG - [MI] Importing 1 media chapters.
Sep 02, 2018 04:51:11.608 [0x7f9b3e74b800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:51:11.608 [0x7f9b3e74b800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:11.609 [0x7f9b3e74b800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:11.611 [0x7f9b3e74b800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:51:11.612 [0x7f9b3e74b800] DEBUG - Updating metadata item (save) (Pinky...He Delivers) with ID 3550
Sep 02, 2018 04:51:11.709 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Sep 02, 2018 04:51:11.711 [0x7f9b3e74b800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Pink Panther/1/Video Ts-33.m4v"
Sep 02, 2018 04:51:11.711 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Sep 02, 2018 04:51:11.712 [0x7f9b3e74b800] DEBUG - [FFMPEG] - Statistics: 371790 bytes read, 4 seeks
Sep 02, 2018 04:51:11.714 [0x7f9b3e74b800] DEBUG - Committing media stream changes for part 7262
Sep 02, 2018 04:51:11.783 [0x7f9b3e74b800] DEBUG - Updating media item 7262, size=131481957, metadata_item_id=3550
Sep 02, 2018 04:51:11.783 [0x7f9b3e74b800] DEBUG - Updating part with ID=7262 [/volume1/Plex/Library/Shared Videos/TV/Pink Panther/1/Video Ts-33.m4v]
Sep 02, 2018 04:51:11.877 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3550&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:11.879 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3550&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:51:11.879 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3550&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:11.881 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3550&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:51:11.882 [0x7f9b2e7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3550&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:51:11.883 [0x7f9b2e7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3550&metadataType=4&state=-1&mediaState=idle
