Sep 02, 2018 04:50:33.095 [0x7f018a656700] 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:50:33.096 [0x7f018a656700] INFO - Linux version: 4.4.59+ (#23739 SMP PREEMPT Tue Jul 10 00:18:17 CST 2018), language: en-US
Sep 02, 2018 04:50:33.096 [0x7f018a656700] INFO - Processor Intel(R) Celeron(R) CPU J3455 @ 1.50GHz
Sep 02, 2018 04:50:33.096 [0x7f018a656700] INFO - /volume1/@appstore/Plex Media Server/Plex Media Scanner --analyze --item 4196,110,3026,3591,156,3009,95,26,3262,146 --log-file-suffix  Analysis
Sep 02, 2018 04:50:33.115 [0x7f0196b7d800] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Sep 02, 2018 04:50:33.379 [0x7f0196b7d800] DEBUG - Analyzing media parts for item 4196 (Through Imperial Eyes): 8120
Sep 02, 2018 04:50:33.381 [0x7f0196b7d800] DEBUG - [ID 8120] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Star Wars Rebels/Season 3/Star.Wars.Rebels.S03E16.720p.BluRay.x264-SPRiNTER.mkv
Sep 02, 2018 04:50:33.382 [0x7f0196b7d800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Star Wars Rebels/Season 3/Star.Wars.Rebels.S03E16.720p.BluRay.x264-SPRiNTER.mkv"
Sep 02, 2018 04:50:33.382 [0x7f0196b7d800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Star Wars Rebels/Season 3/Star.Wars.Rebels.S03E16.720p.BluRay.x264-SPRiNTER.mkv' for reading
Sep 02, 2018 04:50:33.382 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:50:33.414 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:50:33.429 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:33.429 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:33.430 [0x7f0196b7d800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5935 bytes read:33600 seeks:2 nb_streams:2
Sep 02, 2018 04:50:33.431 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:33.431 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:33.432 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:33.432 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:33.433 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:33.433 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:50:33.433 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:50:33.435 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:33.438 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:33.439 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:33.441 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:33.445 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:33.450 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:33.454 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:33.456 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:33.460 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:33.463 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:33.467 [0x7f0196b7d800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:50:33.467 [0x7f0196b7d800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 48285 bytes read:99136 seeks:2 frames:26
Sep 02, 2018 04:50:33.469 [0x7f0196b7d800] DEBUG - [MI]  * e1b267a0d88053acec69130499243af50a145eab [mkv h264/ac3] Duration: 1326144ms, 1280x720 (1.777778) 23.976fps, bitrate=4013kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Star Wars Rebels/Season 3/Star.Wars.Rebels.S03E16.720p.BluRay.x264-SPRiNTER.mkv")
Sep 02, 2018 04:50:33.471 [0x7f0196b7d800] DEBUG - [MI] Importing 5 media chapters.
Sep 02, 2018 04:50:33.471 [0x7f0196b7d800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:50:33.474 [0x7f0196b7d800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Star Wars Rebels/Season 3/Star.Wars.Rebels.S03E16.720p.BluRay.x264-SPRiNTER.mkv"
Sep 02, 2018 04:50:33.476 [0x7f0196b7d800] DEBUG - [FFMPEG] - Statistics: 99136 bytes read, 2 seeks
Sep 02, 2018 04:50:33.479 [0x7f0196b7d800] DEBUG - Updating media item 8120, size=665262316, metadata_item_id=4196
Sep 02, 2018 04:50:33.480 [0x7f0196b7d800] DEBUG - Updating part with ID=8120 [/volume1/Plex/Library/Shared Videos/TV/Star Wars Rebels/Season 3/Star.Wars.Rebels.S03E16.720p.BluRay.x264-SPRiNTER.mkv]
Sep 02, 2018 04:50:33.589 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4196&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:33.592 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4196&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:33.593 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4196&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:33.594 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4196&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:33.595 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4196&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:50:33.597 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=4196&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:50:33.614 [0x7f0196b7d800] DEBUG - Analyzing media parts for item 110 (Life): 116
Sep 02, 2018 04:50:33.615 [0x7f0196b7d800] DEBUG - [ID 116] Media part analysis: /volume1/Plex/Library/Shared Videos/Movies/Life/Life.2017.1080p.BluRay.x264.mkv
Sep 02, 2018 04:50:33.615 [0x7f0196b7d800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/Movies/Life/Life.2017.1080p.BluRay.x264.mkv"
Sep 02, 2018 04:50:33.615 [0x7f0196b7d800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/Movies/Life/Life.2017.1080p.BluRay.x264.mkv' for reading
Sep 02, 2018 04:50:33.615 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:50:33.675 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:50:33.676 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:33.676 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:33.676 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:33.676 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:33.676 [0x7f0196b7d800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6657 bytes read:32768 seeks:0 nb_streams:4
Sep 02, 2018 04:50:33.676 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:33.676 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:33.677 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:33.677 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:33.678 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:33.678 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:50:33.678 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:50:33.681 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:33.689 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:33.691 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:33.696 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:33.705 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:33.713 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:33.721 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:33.726 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:33.732 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:33.738 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:33.759 [0x7f0196b7d800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:50:33.759 [0x7f0196b7d800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 140199 bytes read:163840 seeks:0 frames:58
Sep 02, 2018 04:50:33.759 [0x7f0196b7d800] DEBUG - [MI]  * a7c497d979cb4ccbe93ea8c1fe8f0ce86df614a6 [mkv h264/dca] Duration: 6238232ms, 1920x808 (2.376238) 23.976fps, bitrate=10527kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/Movies/Life/Life.2017.1080p.BluRay.x264.mkv")
Sep 02, 2018 04:50:33.761 [0x7f0196b7d800] DEBUG - [MI] Importing 16 media chapters.
Sep 02, 2018 04:50:33.761 [0x7f0196b7d800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:50:33.765 [0x7f0196b7d800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/Movies/Life/Life.2017.1080p.BluRay.x264.mkv"
Sep 02, 2018 04:50:33.768 [0x7f0196b7d800] DEBUG - [FFMPEG] - Statistics: 163840 bytes read, 0 seeks
Sep 02, 2018 04:50:33.770 [0x7f0196b7d800] DEBUG - Committing media stream changes for part 116
Sep 02, 2018 04:50:33.855 [0x7f0196b7d800] DEBUG - Updating media item 116, size=8209450807, metadata_item_id=110
Sep 02, 2018 04:50:33.855 [0x7f0196b7d800] DEBUG - Updating part with ID=116 [/volume1/Plex/Library/Shared Videos/Movies/Life/Life.2017.1080p.BluRay.x264.mkv]
Sep 02, 2018 04:50:33.950 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:33.952 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:33.953 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:33.955 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:33.955 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:50:33.957 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:50:33.957 [0x7f0196b7d800] DEBUG - Analyzing media parts for item 3026 (The Stolen Earth (1)): 6731
Sep 02, 2018 04:50:33.959 [0x7f0196b7d800] DEBUG - [ID 6731] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 4/Doctor Who (2005) - S04E12 - The Stolen Earth.mkv
Sep 02, 2018 04:50:33.959 [0x7f0196b7d800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 4/Doctor Who (2005) - S04E12 - The Stolen Earth.mkv"
Sep 02, 2018 04:50:33.960 [0x7f0196b7d800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 4/Doctor Who (2005) - S04E12 - The Stolen Earth.mkv' for reading
Sep 02, 2018 04:50:33.960 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:50:33.993 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:50:33.993 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:33.993 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:33.993 [0x7f0196b7d800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5020 bytes read:32768 seeks:0 nb_streams:2
Sep 02, 2018 04:50:33.994 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:33.994 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:33.994 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:33.994 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:33.995 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:33.995 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:50:33.995 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:50:33.998 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:34.003 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.006 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:34.009 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.018 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:34.026 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:34.036 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.040 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.046 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:34.052 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:34.060 [0x7f0196b7d800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:50:34.060 [0x7f0196b7d800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 103507 bytes read:131072 seeks:0 frames:58
Sep 02, 2018 04:50:34.060 [0x7f0196b7d800] DEBUG - [MI]  * fb5c895c89171d296b7d33beb17e05787fe16385 [mkv h264/dca] Duration: 2878792ms, 1872x1080 (1.733333) 23.976fps, bitrate=9771kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 4/Doctor Who (2005) - S04E12 - The Stolen Earth.mkv")
Sep 02, 2018 04:50:34.061 [0x7f0196b7d800] DEBUG - [MI] Importing 12 media chapters.
Sep 02, 2018 04:50:34.061 [0x7f0196b7d800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:50:34.064 [0x7f0196b7d800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 4/Doctor Who (2005) - S04E12 - The Stolen Earth.mkv"
Sep 02, 2018 04:50:34.068 [0x7f0196b7d800] DEBUG - [FFMPEG] - Statistics: 131072 bytes read, 0 seeks
Sep 02, 2018 04:50:34.070 [0x7f0196b7d800] DEBUG - Updating media item 6731, size=3516102217, metadata_item_id=3026
Sep 02, 2018 04:50:34.070 [0x7f0196b7d800] DEBUG - Updating part with ID=6731 [/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 4/Doctor Who (2005) - S04E12 - The Stolen Earth.mkv]
Sep 02, 2018 04:50:34.155 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3026&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:34.157 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3026&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:34.158 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3026&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:34.159 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3026&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:34.160 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3026&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:50:34.161 [0x7f0196b7d800] DEBUG - Analyzing media parts for item 3591 (Oh Varkula): 7301
Sep 02, 2018 04:50:34.162 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3026&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:50:34.163 [0x7f0196b7d800] DEBUG - [ID 7301] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Pink Panther/3/Video Ts-24.m4v
Sep 02, 2018 04:50:34.163 [0x7f0196b7d800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Pink Panther/3/Video Ts-24.m4v"
Sep 02, 2018 04:50:34.163 [0x7f0196b7d800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Pink Panther/3/Video Ts-24.m4v' for reading
Sep 02, 2018 04:50:34.163 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:50:34.177 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Sep 02, 2018 04:50:34.177 [0x7f0196b7d800] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Sep 02, 2018 04:50:34.205 [0x7f0196b7d800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:50:34.222 [0x7f0196b7d800] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 7200, duration: 33267600
Sep 02, 2018 04:50:34.223 [0x7f0196b7d800] DEBUG - [FFMPEG] - Offset DTS by 7200 to make first pts zero.
Sep 02, 2018 04:50:34.225 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Sep 02, 2018 04:50:34.225 [0x7f0196b7d800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:50:34.234 [0x7f0196b7d800] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 0, duration: 17740800
Sep 02, 2018 04:50:34.235 [0x7f0196b7d800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:50:34.236 [0x7f0196b7d800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 137921367 bytes read:335046 seeks:3 nb_streams:3
Sep 02, 2018 04:50:34.236 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:34.236 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:34.241 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:34.241 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:50:34.241 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:50:34.242 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:34.243 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.243 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:34.245 [0x7f0196b7d800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:50:34.245 [0x7f0196b7d800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 986 bytes read:367814 seeks:4 frames:3
Sep 02, 2018 04:50:34.245 [0x7f0196b7d800] DEBUG - [MI] Opening "/volume1/Plex/Library/Shared Videos/TV/Pink Panther/3/Video Ts-24.m4v" for MP4 atom parsing
Sep 02, 2018 04:50:34.245 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Sep 02, 2018 04:50:34.245 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Sep 02, 2018 04:50:34.245 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Skipping mdat atom of size 137651817 at offset 40
Sep 02, 2018 04:50:34.245 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found moov atom of size 269510 at offset 137651857
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found trak atom of size 129700 at offset 137651997
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found trak atom of size 139119 at offset 137781697
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found trak atom of size 439 at offset 137920816
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found mdia atom of size 129544 at offset 137652153
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found minf atom of size 129459 at offset 137652238
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found stbl atom of size 129395 at offset 137652302
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 137652133
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 137652141
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found mdia atom of size 138941 at offset 137781853
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found minf atom of size 138862 at offset 137781932
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found stbl atom of size 138802 at offset 137781992
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 137781833
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 137781841
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found mdia atom of size 339 at offset 137920916
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found minf atom of size 251 at offset 137921004
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found stbl atom of size 175 at offset 137921080
Sep 02, 2018 04:50:34.246 [0x7f0196b7d800] DEBUG - [MI]  * 4029db3ba2e43718628c9b37fd791f109cc2ed9d [mp4 h264/aac] Duration: 369640ms, 720x576 (1.777778) 25.000fps, bitrate=2984kbps, audio channels=2, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Pink Panther/3/Video Ts-24.m4v")
Sep 02, 2018 04:50:34.248 [0x7f0196b7d800] DEBUG - [MI] Importing 1 media chapters.
Sep 02, 2018 04:50:34.248 [0x7f0196b7d800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:50:34.249 [0x7f0196b7d800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:50:34.249 [0x7f0196b7d800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:50:34.251 [0x7f0196b7d800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:50:34.252 [0x7f0196b7d800] DEBUG - Updating metadata item (save) (Oh Varkula) with ID 3591
Sep 02, 2018 04:50:34.354 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Sep 02, 2018 04:50:34.355 [0x7f0196b7d800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Pink Panther/3/Video Ts-24.m4v"
Sep 02, 2018 04:50:34.356 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Sep 02, 2018 04:50:34.356 [0x7f0196b7d800] DEBUG - [FFMPEG] - Statistics: 367814 bytes read, 4 seeks
Sep 02, 2018 04:50:34.358 [0x7f0196b7d800] DEBUG - Committing media stream changes for part 7301
Sep 02, 2018 04:50:34.454 [0x7f0196b7d800] DEBUG - Updating media item 7301, size=137921367, metadata_item_id=3591
Sep 02, 2018 04:50:34.455 [0x7f0196b7d800] DEBUG - Updating part with ID=7301 [/volume1/Plex/Library/Shared Videos/TV/Pink Panther/3/Video Ts-24.m4v]
Sep 02, 2018 04:50:34.533 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3591&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:34.535 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3591&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:34.535 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3591&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:34.537 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3591&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:34.538 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3591&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:50:34.540 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3591&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:50:34.546 [0x7f0196b7d800] DEBUG - It took 10.000000 ms to retrieve 102 items.
Sep 02, 2018 04:50:34.560 [0x7f0196b7d800] DEBUG - Analyzing media parts for item 156 (The Secret Life of Pets): 168
Sep 02, 2018 04:50:34.561 [0x7f0196b7d800] DEBUG - [ID 168] Media part analysis: /volume1/Plex/Library/Shared Videos/Movies/The Secret Life of Pets/The.Secret.Life.of.Pets.2016.1080p.BluRay.x264.mkv
Sep 02, 2018 04:50:34.561 [0x7f0196b7d800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/Movies/The Secret Life of Pets/The.Secret.Life.of.Pets.2016.1080p.BluRay.x264.mkv"
Sep 02, 2018 04:50:34.561 [0x7f0196b7d800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/Movies/The Secret Life of Pets/The.Secret.Life.of.Pets.2016.1080p.BluRay.x264.mkv' for reading
Sep 02, 2018 04:50:34.561 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:50:34.596 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:50:34.612 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:34.612 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:34.612 [0x7f0196b7d800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6683 bytes read:33603 seeks:2 nb_streams:2
Sep 02, 2018 04:50:34.613 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:34.613 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:34.614 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:34.614 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:34.614 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:34.614 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:50:34.614 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:50:34.618 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:34.622 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.624 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:34.628 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.637 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:34.646 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:34.656 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.660 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.667 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:34.674 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:34.680 [0x7f0196b7d800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:50:34.680 [0x7f0196b7d800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 49489 bytes read:99139 seeks:2 frames:26
Sep 02, 2018 04:50:34.681 [0x7f0196b7d800] DEBUG - [MI]  * 35d8dba8eed29712f3a4cbcd2c8766933d724e5a [mkv h264/ac3] Duration: 5187200ms, 1920x1040 (1.846154) 23.976fps, bitrate=7234kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/Movies/The Secret Life of Pets/The.Secret.Life.of.Pets.2016.1080p.BluRay.x264.mkv")
Sep 02, 2018 04:50:34.682 [0x7f0196b7d800] DEBUG - [MI] Importing 20 media chapters.
Sep 02, 2018 04:50:34.682 [0x7f0196b7d800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:50:34.685 [0x7f0196b7d800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:50:34.685 [0x7f0196b7d800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:50:34.687 [0x7f0196b7d800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:50:34.688 [0x7f0196b7d800] DEBUG - Updating metadata item (save) (The Secret Life of Pets) with ID 156
Sep 02, 2018 04:50:34.803 [0x7f0196b7d800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/Movies/The Secret Life of Pets/The.Secret.Life.of.Pets.2016.1080p.BluRay.x264.mkv"
Sep 02, 2018 04:50:34.806 [0x7f0196b7d800] DEBUG - [FFMPEG] - Statistics: 99139 bytes read, 2 seeks
Sep 02, 2018 04:50:34.808 [0x7f0196b7d800] DEBUG - Updating media item 168, size=4690726111, metadata_item_id=156
Sep 02, 2018 04:50:34.809 [0x7f0196b7d800] DEBUG - Updating part with ID=168 [/volume1/Plex/Library/Shared Videos/Movies/The Secret Life of Pets/The.Secret.Life.of.Pets.2016.1080p.BluRay.x264.mkv]
Sep 02, 2018 04:50:34.895 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:34.897 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:34.898 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:34.900 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:34.901 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:50:34.901 [0x7f0196b7d800] DEBUG - Analyzing media parts for item 3009 (Human Nature (1)): 6714
Sep 02, 2018 04:50:34.903 [0x7f0196b7d800] DEBUG - [ID 6714] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 3/Doctor Who (2005) - S03E08 - Human Nature.mkv
Sep 02, 2018 04:50:34.904 [0x7f0196b7d800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 3/Doctor Who (2005) - S03E08 - Human Nature.mkv"
Sep 02, 2018 04:50:34.904 [0x7f0196b7d800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 3/Doctor Who (2005) - S03E08 - Human Nature.mkv' for reading
Sep 02, 2018 04:50:34.904 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:50:34.904 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:50:34.942 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:50:34.942 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:34.942 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:34.942 [0x7f0196b7d800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5066 bytes read:32768 seeks:0 nb_streams:2
Sep 02, 2018 04:50:34.943 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:34.943 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:34.943 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:34.943 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:34.944 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:34.944 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:50:34.944 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:50:34.947 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:34.952 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.955 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:34.959 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.967 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:34.975 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:34.985 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.990 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:34.996 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:35.002 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:35.009 [0x7f0196b7d800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:50:35.009 [0x7f0196b7d800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 103552 bytes read:131072 seeks:0 frames:58
Sep 02, 2018 04:50:35.009 [0x7f0196b7d800] DEBUG - [MI]  * 12245b37348ebf2f29674f36c4e49d0af4dbaf4f [mkv h264/dca] Duration: 2834039ms, 1872x1080 (1.733333) 23.976fps, bitrate=9933kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 3/Doctor Who (2005) - S03E08 - Human Nature.mkv")
Sep 02, 2018 04:50:35.010 [0x7f0196b7d800] DEBUG - [MI] Importing 13 media chapters.
Sep 02, 2018 04:50:35.011 [0x7f0196b7d800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:50:35.014 [0x7f0196b7d800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 3/Doctor Who (2005) - S03E08 - Human Nature.mkv"
Sep 02, 2018 04:50:35.017 [0x7f0196b7d800] DEBUG - [FFMPEG] - Statistics: 131072 bytes read, 0 seeks
Sep 02, 2018 04:50:35.017 [0x7f0196b7d800] DEBUG - Streams didn't change in analysis, not saving.
Sep 02, 2018 04:50:35.018 [0x7f0196b7d800] DEBUG - Updating media item 6714, size=3518900589, metadata_item_id=3009
Sep 02, 2018 04:50:35.018 [0x7f0196b7d800] DEBUG - Updating part with ID=6714 [/volume1/Plex/Library/Shared Videos/TV/Doctor Who (2005)/Season 3/Doctor Who (2005) - S03E08 - Human Nature.mkv]
Sep 02, 2018 04:50:35.122 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3009&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:35.126 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3009&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:35.127 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3009&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:35.129 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3009&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:35.129 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3009&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:50:35.132 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3009&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:50:35.137 [0x7f0196b7d800] DEBUG - Analyzing media parts for item 95 (Murder by Death): 101
Sep 02, 2018 04:50:35.137 [0x7f0196b7d800] DEBUG - [ID 101] Media part analysis: /volume1/Plex/Library/Shared Videos/Movies/Murder By Death/Murder_by_Death_t00.mkv
Sep 02, 2018 04:50:35.140 [0x7f0196b7d800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/Movies/Murder By Death/Murder_by_Death_t00.mkv"
Sep 02, 2018 04:50:35.140 [0x7f0196b7d800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/Movies/Murder By Death/Murder_by_Death_t00.mkv' for reading
Sep 02, 2018 04:50:35.140 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:50:35.208 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:50:35.217 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:35.217 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:35.217 [0x7f0196b7d800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 4267 bytes read:33592 seeks:2 nb_streams:2
Sep 02, 2018 04:50:35.218 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:50:35.219 [0x7f0196b7d800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:50:35.219 [0x7f0196b7d800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 15316 bytes read:66360 seeks:2 frames:5
Sep 02, 2018 04:50:35.219 [0x7f0196b7d800] DEBUG - [MI]  * 31b667821e7178be1f6bc6381c27939e023483c4 [mkv mpeg2video/ac3] Duration: 5678673ms, 720x480 (1.777070) 29.970fps, bitrate=4800kbps, audio channels=2, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/Movies/Murder By Death/Murder_by_Death_t00.mkv")
Sep 02, 2018 04:50:35.222 [0x7f0196b7d800] DEBUG - [MI] Importing 28 media chapters.
Sep 02, 2018 04:50:35.222 [0x7f0196b7d800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:50:35.225 [0x7f0196b7d800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:50:35.225 [0x7f0196b7d800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:50:35.227 [0x7f0196b7d800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Sep 02, 2018 04:50:35.228 [0x7f0196b7d800] DEBUG - Updating metadata item (save) (Murder by Death) with ID 95
Sep 02, 2018 04:50:35.315 [0x7f0196b7d800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/Movies/Murder By Death/Murder_by_Death_t00.mkv"
Sep 02, 2018 04:50:35.315 [0x7f0196b7d800] DEBUG - [FFMPEG] - Statistics: 66360 bytes read, 2 seeks
Sep 02, 2018 04:50:35.318 [0x7f0196b7d800] DEBUG - Updating media item 101, size=3407478402, metadata_item_id=95
Sep 02, 2018 04:50:35.318 [0x7f0196b7d800] DEBUG - Updating part with ID=101 [/volume1/Plex/Library/Shared Videos/Movies/Murder By Death/Murder_by_Death_t00.mkv]
Sep 02, 2018 04:50:35.422 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=95&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:35.424 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=95&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:35.424 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=95&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:35.427 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=95&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:35.427 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=95&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:50:35.430 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=95&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:50:35.442 [0x7f0196b7d800] DEBUG - Analyzing media parts for item 26 (Jodorowsky's Dune): 26
Sep 02, 2018 04:50:35.442 [0x7f0196b7d800] DEBUG - [ID 26] Media part analysis: /volume1/Plex/Library/Shared Videos/Movies/Jodorowsky's Dune/Jodorowsky's Dune (2013) [1080p]/Jodorowskys.Dune.2013.1080p.BluRay.x264.YIFY.mp4
Sep 02, 2018 04:50:35.455 [0x7f0196b7d800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/Movies/Jodorowsky's Dune/Jodorowsky's Dune (2013) [1080p]/Jodorowskys.Dune.2013.1080p.BluRay.x264.YIFY.mp4"
Sep 02, 2018 04:50:35.456 [0x7f0196b7d800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/Movies/Jodorowsky's Dune/Jodorowsky's Dune (2013) [1080p]/Jodorowskys.Dune.2013.1080p.BluRay.x264.YIFY.mp4' for reading
Sep 02, 2018 04:50:35.456 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:50:35.472 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Sep 02, 2018 04:50:35.472 [0x7f0196b7d800] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Sep 02, 2018 04:50:35.472 [0x7f0196b7d800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:50:35.533 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Sep 02, 2018 04:50:35.533 [0x7f0196b7d800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:50:35.578 [0x7f0196b7d800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1326563716 bytes read:2817757 seeks:1 nb_streams:2
Sep 02, 2018 04:50:35.578 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:35.579 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:35.580 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:35.580 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:35.580 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:50:35.580 [0x7f0196b7d800] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Sep 02, 2018 04:50:35.580 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:50:35.584 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:35.592 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:35.592 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:35.592 [0x7f0196b7d800] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Sep 02, 2018 04:50:35.595 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:35.601 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:35.601 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:35.601 [0x7f0196b7d800] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Sep 02, 2018 04:50:35.612 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:35.612 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:35.612 [0x7f0196b7d800] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Sep 02, 2018 04:50:35.625 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:35.625 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:35.625 [0x7f0196b7d800] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Sep 02, 2018 04:50:35.637 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:35.637 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:35.637 [0x7f0196b7d800] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Sep 02, 2018 04:50:35.642 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:35.643 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:35.643 [0x7f0196b7d800] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Sep 02, 2018 04:50:35.652 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:35.652 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:35.652 [0x7f0196b7d800] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Sep 02, 2018 04:50:35.662 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:35.662 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:35.662 [0x7f0196b7d800] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Sep 02, 2018 04:50:35.671 [0x7f0196b7d800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:50:35.671 [0x7f0196b7d800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2818285 bytes read:2850525 seeks:2 frames:13
Sep 02, 2018 04:50:35.671 [0x7f0196b7d800] DEBUG - [MI] Opening "/volume1/Plex/Library/Shared Videos/Movies/Jodorowsky's Dune/Jodorowsky's Dune (2013) [1080p]/Jodorowskys.Dune.2013.1080p.BluRay.x264.YIFY.mp4" for MP4 atom parsing
Sep 02, 2018 04:50:35.671 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 24 at offset 0
Sep 02, 2018 04:50:35.671 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found moov atom of size 2799362 at offset 24
Sep 02, 2018 04:50:35.674 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found trak atom of size 1596584 at offset 161
Sep 02, 2018 04:50:35.674 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found trak atom of size 1202641 at offset 1596745
Sep 02, 2018 04:50:35.674 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found mdia atom of size 1596484 at offset 261
Sep 02, 2018 04:50:35.674 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found minf atom of size 1596346 at offset 399
Sep 02, 2018 04:50:35.674 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found stbl atom of size 1596282 at offset 463
Sep 02, 2018 04:50:35.674 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found mdia atom of size 1202541 at offset 1596845
Sep 02, 2018 04:50:35.674 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found minf atom of size 1202446 at offset 1596940
Sep 02, 2018 04:50:35.674 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found stbl atom of size 1202386 at offset 1597000
Sep 02, 2018 04:50:35.674 [0x7f0196b7d800] DEBUG - [MI]  * 91369f60a86b36369ad442eeeb9e82b1fc7b7203 [mp4 h264/aac] Duration: 5414485ms, 1920x1080 (1.777778) 23.976fps, bitrate=1960kbps, audio channels=2, optimizedForStreaming=1 ("/volume1/Plex/Library/Shared Videos/Movies/Jodorowsky's Dune/Jodorowsky's Dune (2013) [1080p]/Jodorowskys.Dune.2013.1080p.BluRay.x264.YIFY.mp4")
Sep 02, 2018 04:50:35.675 [0x7f0196b7d800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:50:35.676 [0x7f0196b7d800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/Movies/Jodorowsky's Dune/Jodorowsky's Dune (2013) [1080p]/Jodorowskys.Dune.2013.1080p.BluRay.x264.YIFY.mp4"
Sep 02, 2018 04:50:35.681 [0x7f0196b7d800] DEBUG - [FFMPEG] - Statistics: 2850525 bytes read, 2 seeks
Sep 02, 2018 04:50:35.682 [0x7f0196b7d800] DEBUG - Committing media stream changes for part 26
Sep 02, 2018 04:50:35.799 [0x7f0196b7d800] DEBUG - Updating media item 26, size=1326563747, metadata_item_id=26
Sep 02, 2018 04:50:35.799 [0x7f0196b7d800] DEBUG - Updating part with ID=26 [/volume1/Plex/Library/Shared Videos/Movies/Jodorowsky's Dune/Jodorowsky's Dune (2013) [1080p]/Jodorowskys.Dune.2013.1080p.BluRay.x264.YIFY.mp4]
Sep 02, 2018 04:50:35.878 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=26&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:35.880 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=26&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:35.881 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=26&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:35.883 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=26&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:35.884 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=26&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:50:35.884 [0x7f0196b7d800] DEBUG - Analyzing media parts for item 3262 (Music to Die For): 6951
Sep 02, 2018 04:50:35.886 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=26&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:50:35.886 [0x7f0196b7d800] DEBUG - [ID 6951] Media part analysis: /volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S02/Lewis S02E02 Music to Die For x264 RB58.mp4
Sep 02, 2018 04:50:35.887 [0x7f0196b7d800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S02/Lewis S02E02 Music to Die For x264 RB58.mp4"
Sep 02, 2018 04:50:35.887 [0x7f0196b7d800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S02/Lewis S02E02 Music to Die For x264 RB58.mp4' for reading
Sep 02, 2018 04:50:35.887 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:50:35.904 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Sep 02, 2018 04:50:35.905 [0x7f0196b7d800] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Sep 02, 2018 04:50:35.914 [0x7f0196b7d800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:50:35.957 [0x7f0196b7d800] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 14400, duration: 502750800
Sep 02, 2018 04:50:35.973 [0x7f0196b7d800] DEBUG - [FFMPEG] - Offset DTS by 14400 to make first pts zero.
Sep 02, 2018 04:50:35.999 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Sep 02, 2018 04:50:35.999 [0x7f0196b7d800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:50:36.018 [0x7f0196b7d800] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Sep 02, 2018 04:50:36.019 [0x7f0196b7d800] DEBUG - [FFMPEG] - Processing st: 2, edit list 0 - media time: 14400, duration: 502750800
Sep 02, 2018 04:50:36.019 [0x7f0196b7d800] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Sep 02, 2018 04:50:36.188 [0x7f0196b7d800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 742898371 bytes read:3180689 seeks:11 nb_streams:3
Sep 02, 2018 04:50:36.188 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:36.188 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:36.190 [0x7f0196b7d800] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Sep 02, 2018 04:50:36.190 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:36.190 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:50:36.191 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:50:36.191 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:36.192 [0x7f0196b7d800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:50:36.192 [0x7f0196b7d800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 1082 bytes read:3213457 seeks:12 frames:9
Sep 02, 2018 04:50:36.193 [0x7f0196b7d800] DEBUG - [MI] Opening "/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S02/Lewis S02E02 Music to Die For x264 RB58.mp4" for MP4 atom parsing
Sep 02, 2018 04:50:36.193 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Sep 02, 2018 04:50:36.193 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Sep 02, 2018 04:50:36.193 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Skipping mdat atom of size 739890805 at offset 160
Sep 02, 2018 04:50:36.193 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found moov atom of size 3007382 at offset 739890965
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found trak atom of size 1827773 at offset 739891105
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found trak atom of size 1178770 at offset 741718878
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found trak atom of size 588 at offset 742897648
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found mdia atom of size 1827617 at offset 739891205
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found minf atom of size 1827544 at offset 739891278
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found stbl atom of size 1827480 at offset 739891342
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 741718822
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 741718830
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found mdia atom of size 1178648 at offset 741718978
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found minf atom of size 1178575 at offset 741719051
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found stbl atom of size 1178515 at offset 741719111
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found mdia atom of size 452 at offset 742897748
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found minf atom of size 379 at offset 742897821
Sep 02, 2018 04:50:36.196 [0x7f0196b7d800] DEBUG - Mp4AtomParser: Found stbl atom of size 259 at offset 742897941
Sep 02, 2018 04:50:36.197 [0x7f0196b7d800] DEBUG - [MI]  * 39bfa8d8f6196c7a32bfedd1c769e420c7bf187a [mp4 h264/aac] Duration: 5586120ms, 716x572 (1.780264) 24.999fps, bitrate=1063kbps, audio channels=2, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S02/Lewis S02E02 Music to Die For x264 RB58.mp4")
Sep 02, 2018 04:50:36.198 [0x7f0196b7d800] DEBUG - [MI] Importing 8 media chapters.
Sep 02, 2018 04:50:36.198 [0x7f0196b7d800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:50:36.201 [0x7f0196b7d800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S02/Lewis S02E02 Music to Die For x264 RB58.mp4"
Sep 02, 2018 04:50:36.203 [0x7f0196b7d800] DEBUG - [FFMPEG] - Statistics: 3213457 bytes read, 12 seeks
Sep 02, 2018 04:50:36.204 [0x7f0196b7d800] DEBUG - Committing media stream changes for part 6951
Sep 02, 2018 04:50:36.321 [0x7f0196b7d800] DEBUG - Updating media item 6951, size=742898479, metadata_item_id=3262
Sep 02, 2018 04:50:36.322 [0x7f0196b7d800] DEBUG - Updating part with ID=6951 [/volume1/Plex/Library/Shared Videos/TV/Lewis/Lewis S02/Lewis S02E02 Music to Die For x264 RB58.mp4]
Sep 02, 2018 04:50:36.411 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3262&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:36.413 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3262&metadataType=4&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:36.414 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3262&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:36.416 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3262&metadataType=4&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:36.416 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3262&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:50:36.418 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=3262&metadataType=4&state=-1&mediaState=idle
Sep 02, 2018 04:50:36.524 [0x7f0196b7d800] DEBUG - Analyzing media parts for item 146 (10 Cloverfield Lane): 158
Sep 02, 2018 04:50:36.524 [0x7f0196b7d800] DEBUG - [ID 158] Media part analysis: /volume1/Plex/Library/Shared Videos/Movies/10 Cloverfield Lane/10.Cloverfield.Lane.2016.1080p.BluRay.x264.mkv
Sep 02, 2018 04:50:36.524 [0x7f0196b7d800] DEBUG - [MI] Opening input file: "/volume1/Plex/Library/Shared Videos/Movies/10 Cloverfield Lane/10.Cloverfield.Lane.2016.1080p.BluRay.x264.mkv"
Sep 02, 2018 04:50:36.524 [0x7f0196b7d800] DEBUG - [FFMPEG] - Opening '/volume1/Plex/Library/Shared Videos/Movies/10 Cloverfield Lane/10.Cloverfield.Lane.2016.1080p.BluRay.x264.mkv' for reading
Sep 02, 2018 04:50:36.524 [0x7f0196b7d800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Sep 02, 2018 04:50:36.567 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Sep 02, 2018 04:50:36.605 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:36.605 [0x7f0196b7d800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Sep 02, 2018 04:50:36.605 [0x7f0196b7d800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6485 bytes read:33603 seeks:2 nb_streams:2
Sep 02, 2018 04:50:36.605 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:36.605 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:36.606 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Sep 02, 2018 04:50:36.606 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Sep 02, 2018 04:50:36.607 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Sep 02, 2018 04:50:36.607 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Sep 02, 2018 04:50:36.607 [0x7f0196b7d800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Sep 02, 2018 04:50:36.610 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:36.613 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:36.615 [0x7f0196b7d800] DEBUG - [FFMPEG] - no picture 
Sep 02, 2018 04:50:36.618 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:36.625 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:36.632 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:36.639 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:36.643 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Sep 02, 2018 04:50:36.648 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:36.653 [0x7f0196b7d800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Sep 02, 2018 04:50:36.661 [0x7f0196b7d800] DEBUG - [FFMPEG] - All info found
Sep 02, 2018 04:50:36.661 [0x7f0196b7d800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 49100 bytes read:99139 seeks:2 frames:26
Sep 02, 2018 04:50:36.661 [0x7f0196b7d800] DEBUG - [MI]  * 8f84c9bb09ea810d4ae3850ca1de70d90738d80a [mkv h264/ac3] Duration: 6214816ms, 1920x800 (2.400000) 23.976fps, bitrate=10572kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/Plex/Library/Shared Videos/Movies/10 Cloverfield Lane/10.Cloverfield.Lane.2016.1080p.BluRay.x264.mkv")
Sep 02, 2018 04:50:36.663 [0x7f0196b7d800] DEBUG - [MI] Importing 16 media chapters.
Sep 02, 2018 04:50:36.663 [0x7f0196b7d800] DEBUG - [MI] Chapters had generic titles, clearing.
Sep 02, 2018 04:50:36.667 [0x7f0196b7d800] DEBUG - [MI] Closing input file: "/volume1/Plex/Library/Shared Videos/Movies/10 Cloverfield Lane/10.Cloverfield.Lane.2016.1080p.BluRay.x264.mkv"
Sep 02, 2018 04:50:36.670 [0x7f0196b7d800] DEBUG - [FFMPEG] - Statistics: 99139 bytes read, 2 seeks
Sep 02, 2018 04:50:36.672 [0x7f0196b7d800] DEBUG - Updating media item 158, size=8213012031, metadata_item_id=146
Sep 02, 2018 04:50:36.673 [0x7f0196b7d800] DEBUG - Updating part with ID=158 [/volume1/Plex/Library/Shared Videos/Movies/10 Cloverfield Lane/10.Cloverfield.Lane.2016.1080p.BluRay.x264.mkv]
Sep 02, 2018 04:50:36.766 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=146&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:36.768 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=146&metadataType=1&state=-1&mediaState=analyzing
Sep 02, 2018 04:50:36.769 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=146&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:36.771 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=146&metadataType=1&state=-1&mediaState=thumbnailing
Sep 02, 2018 04:50:36.772 [0x7f0185fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=146&metadataType=1&state=-1&mediaState=idle
Sep 02, 2018 04:50:36.773 [0x7f0185fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=146&metadataType=1&state=-1&mediaState=idle
