Feb 04, 2019 00:04:42.211 [0x7fc566664700] INFO - Plex Media Scanner v1.14.1.5488-cc260c476 - ubuntu PC x86_64 - build: linux-ubuntu-x86_64 - GMT 13:00
Feb 04, 2019 00:04:42.211 [0x7fc566664700] INFO - Linux version: 4.9.0-8-amd64 (#1 SMP Debian 4.9.130-2 (2018-10-27)), language: en-NZ
Feb 04, 2019 00:04:42.211 [0x7fc566664700] INFO - Processor Intel(R) Core(TM) i5 CPU       M 480  @ 2.67GHz
Feb 04, 2019 00:04:42.211 [0x7fc566664700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 4023,8312,8255,8262,4076,8397,8342,8373,4061,4065 --log-file-suffix  Analysis
Feb 04, 2019 00:04:42.218 [0x7fc571ebcd00] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Feb 04, 2019 00:04:42.386 [0x7fc571ebcd00] DEBUG - It took 10.000000 ms to retrieve 112 items.
Feb 04, 2019 00:04:42.401 [0x7fc571ebcd00] DEBUG - Analyzing media parts for item 4023 (The Hunger Games): 10568
Feb 04, 2019 00:04:42.401 [0x7fc571ebcd00] DEBUG - [ID 10625] Media part analysis: /mnt/wd/4t/Plex/Movies/Hunger Games/The Hunger Games (2012).mkv
Feb 04, 2019 00:04:42.417 [0x7fc571ebcd00] DEBUG - [MI] Opening input file: "/mnt/wd/4t/Plex/Movies/Hunger Games/The Hunger Games (2012).mkv"
Feb 04, 2019 00:04:42.418 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Opening '/mnt/wd/4t/Plex/Movies/Hunger Games/The Hunger Games (2012).mkv' for reading
Feb 04, 2019 00:04:42.418 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Feb 04, 2019 00:04:42.510 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Feb 04, 2019 00:04:42.526 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:42.526 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:42.526 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:42.527 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 834 bytes read:65536 seeks:2 nb_streams:3
Feb 04, 2019 00:04:42.527 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:42.527 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:42.530 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:42.530 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:42.531 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Feb 04, 2019 00:04:42.531 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Feb 04, 2019 00:04:42.531 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Feb 04, 2019 00:04:42.533 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:42.535 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:42.536 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:42.539 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:42.543 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:42.548 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:42.553 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:42.555 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:42.559 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:42.562 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:42.566 [0x7fc571ebcd00] DEBUG - [FFMPEG] - All info found
Feb 04, 2019 00:04:42.566 [0x7fc571ebcd00] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 28364 bytes read:98304 seeks:2 frames:36
Feb 04, 2019 00:04:42.567 [0x7fc571ebcd00] DEBUG - [MI]  * d214f165f3e8e4acd6299a6a4a68dd721bb4e745 [mkv h264/ac3] Duration: 8576590ms, 1920x800 (2.400000) 23.976fps, bitrate=8040kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/wd/4t/Plex/Movies/Hunger Games/The Hunger Games (2012).mkv")
Feb 04, 2019 00:04:42.568 [0x7fc571ebcd00] DEBUG - [MI] Importing 16 media chapters.
Feb 04, 2019 00:04:42.569 [0x7fc571ebcd00] DEBUG - [MI] Chapters had generic titles, clearing.
Feb 04, 2019 00:04:42.570 [0x7fc571ebcd00] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:42.571 [0x7fc571ebcd00] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:42.574 [0x7fc571ebcd00] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:42.575 [0x7fc571ebcd00] DEBUG - Updating metadata item (save) (The Hunger Games) with ID 4023
Feb 04, 2019 00:04:42.698 [0x7fc571ebcd00] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:42.699 [0x7fc571ebcd00] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:42.702 [0x7fc571ebcd00] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:42.702 [0x7fc571ebcd00] DEBUG - Updating metadata item (save) (The Hunger Games) with ID 4023
Feb 04, 2019 00:04:42.956 [0x7fc571ebcd00] DEBUG - [MI] Closing input file: "/mnt/wd/4t/Plex/Movies/Hunger Games/The Hunger Games (2012).mkv"
Feb 04, 2019 00:04:42.958 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Feb 04, 2019 00:04:42.961 [0x7fc571ebcd00] DEBUG - Committing media stream changes for part 10625
Feb 04, 2019 00:04:43.016 [0x7fc571ebcd00] DEBUG - Updating media item 10568, size=8619569166, metadata_item_id=4023
Feb 04, 2019 00:04:43.037 [0x7fc571ebcd00] DEBUG - Updating part with ID=10625 [/mnt/wd/4t/Plex/Movies/Hunger Games/The Hunger Games (2012).mkv]
Feb 04, 2019 00:04:43.075 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4023&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:43.076 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4023&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:43.077 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4023&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:43.078 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4023&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:43.079 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4023&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:43.081 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4023&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:43.097 [0x7fc571ebcd00] DEBUG - Analyzing media parts for item 8312 (The Grey): 10318
Feb 04, 2019 00:04:43.097 [0x7fc571ebcd00] DEBUG - [ID 10375] Media part analysis: /mnt/wd/4t/Plex/Movies/The Grey (2011).mkv
Feb 04, 2019 00:04:43.108 [0x7fc571ebcd00] DEBUG - [MI] Opening input file: "/mnt/wd/4t/Plex/Movies/The Grey (2011).mkv"
Feb 04, 2019 00:04:43.108 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Opening '/mnt/wd/4t/Plex/Movies/The Grey (2011).mkv' for reading
Feb 04, 2019 00:04:43.108 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Feb 04, 2019 00:04:43.111 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Feb 04, 2019 00:04:43.122 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:43.122 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:43.122 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:43.122 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 834 bytes read:65536 seeks:2 nb_streams:3
Feb 04, 2019 00:04:43.122 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:43.123 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:43.124 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:43.124 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:43.124 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Feb 04, 2019 00:04:43.124 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Feb 04, 2019 00:04:43.124 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Feb 04, 2019 00:04:43.127 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:43.130 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:43.132 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:43.135 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:43.140 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:43.145 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:43.150 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:43.152 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:43.156 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:43.159 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:43.163 [0x7fc571ebcd00] DEBUG - [FFMPEG] - All info found
Feb 04, 2019 00:04:43.163 [0x7fc571ebcd00] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 28433 bytes read:98304 seeks:2 frames:36
Feb 04, 2019 00:04:43.163 [0x7fc571ebcd00] DEBUG - [MI]  * 2cad45fbaf1a12b238ff94660aac1e035545bd20 [mkv h264/ac3] Duration: 7031630ms, 1920x800 (2.400000) 23.976fps, bitrate=11071kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/wd/4t/Plex/Movies/The Grey (2011).mkv")
Feb 04, 2019 00:04:43.164 [0x7fc571ebcd00] DEBUG - [MI] Importing 14 media chapters.
Feb 04, 2019 00:04:43.164 [0x7fc571ebcd00] DEBUG - [MI] Chapters had generic titles, clearing.
Feb 04, 2019 00:04:43.166 [0x7fc571ebcd00] DEBUG - [MI] Closing input file: "/mnt/wd/4t/Plex/Movies/The Grey (2011).mkv"
Feb 04, 2019 00:04:43.168 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Feb 04, 2019 00:04:43.169 [0x7fc571ebcd00] DEBUG - Committing media stream changes for part 10375
Feb 04, 2019 00:04:43.189 [0x7fc571ebcd00] DEBUG - Updating media item 10318, size=9731510783, metadata_item_id=8312
Feb 04, 2019 00:04:43.190 [0x7fc571ebcd00] DEBUG - Updating part with ID=10375 [/mnt/wd/4t/Plex/Movies/The Grey (2011).mkv]
Feb 04, 2019 00:04:43.218 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8312&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:43.219 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8312&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:43.220 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8312&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:43.221 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8312&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:43.221 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8312&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:43.223 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8312&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:43.238 [0x7fc571ebcd00] DEBUG - Analyzing media parts for item 8255 (M*A*S*H): 10261
Feb 04, 2019 00:04:43.238 [0x7fc571ebcd00] DEBUG - [ID 10318] Media part analysis: /mnt/wd/4t/Plex/Movies/Mash - The Movie (1970).m4v
Feb 04, 2019 00:04:43.246 [0x7fc571ebcd00] DEBUG - [MI] Opening input file: "/mnt/wd/4t/Plex/Movies/Mash - The Movie (1970).m4v"
Feb 04, 2019 00:04:43.246 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Opening '/mnt/wd/4t/Plex/Movies/Mash - The Movie (1970).m4v' for reading
Feb 04, 2019 00:04:43.246 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Feb 04, 2019 00:04:43.265 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Feb 04, 2019 00:04:43.265 [0x7fc571ebcd00] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Feb 04, 2019 00:04:43.283 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:43.388 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 3000, duration: 600132690
Feb 04, 2019 00:04:43.400 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Offset DTS by 3000 to make first pts zero.
Feb 04, 2019 00:04:43.428 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting codecpar->delay to 1 for stream st: 0
Feb 04, 2019 00:04:43.428 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:43.527 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 0, duration: 320067600
Feb 04, 2019 00:04:43.547 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:43.578 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:43.579 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1668535213 bytes read:5724542 seeks:3 nb_streams:4
Feb 04, 2019 00:04:43.579 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:43.579 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:43.580 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Feb 04, 2019 00:04:43.580 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Feb 04, 2019 00:04:43.580 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Feb 04, 2019 00:04:43.581 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:43.581 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:43.582 [0x7fc571ebcd00] DEBUG - [FFMPEG] - All info found
Feb 04, 2019 00:04:43.582 [0x7fc571ebcd00] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2577 bytes read:5757310 seeks:4 frames:5
Feb 04, 2019 00:04:43.582 [0x7fc571ebcd00] DEBUG - [MI] Opening "/mnt/wd/4t/Plex/Movies/Mash - The Movie (1970).m4v" for MP4 atom parsing
Feb 04, 2019 00:04:43.582 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Feb 04, 2019 00:04:43.582 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Feb 04, 2019 00:04:43.582 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping mdat atom of size 1662876167 at offset 40
Feb 04, 2019 00:04:43.582 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found moov atom of size 5659006 at offset 1662876207
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 2323088 at offset 1662876347
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 2501047 at offset 1665199435
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 833952 at offset 1667700482
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 667 at offset 1668534434
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 2322932 at offset 1662876503
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 2322847 at offset 1662876588
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 2322783 at offset 1662876652
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 1662876483
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 1662876491
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - [MI] Determined stream id 4 has chapters, not subtitles
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 2500869 at offset 1665199591
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 2500790 at offset 1665199670
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 2500730 at offset 1665199730
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 1665199571
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 1665199579
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - [MI] Determined stream id 4 has chapters, not subtitles
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 833810 at offset 1667700602
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 833731 at offset 1667700681
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 833671 at offset 1667700741
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 1667700582
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 1667700590
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - [MI] Determined stream id 4 has chapters, not subtitles
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 567 at offset 1668534534
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 479 at offset 1668534622
Feb 04, 2019 00:04:43.586 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 403 at offset 1668534698
Feb 04, 2019 00:04:43.587 [0x7fc571ebcd00] DEBUG - [MI]  * adecf2a29ea7de26707ac4e89dec36d610a4524e [mp4 h264/aac] Duration: 6668148ms, 720x430 (2.385877) 25.000fps, bitrate=2001kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/wd/4t/Plex/Movies/Mash - The Movie (1970).m4v")
Feb 04, 2019 00:04:43.587 [0x7fc571ebcd00] DEBUG - [MI] Importing 15 media chapters.
Feb 04, 2019 00:04:43.588 [0x7fc571ebcd00] DEBUG - [MI] Chapters had generic titles, clearing.
Feb 04, 2019 00:04:43.590 [0x7fc571ebcd00] DEBUG - [MI] Closing input file: "/mnt/wd/4t/Plex/Movies/Mash - The Movie (1970).m4v"
Feb 04, 2019 00:04:43.591 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Statistics: 5757310 bytes read, 4 seeks
Feb 04, 2019 00:04:43.591 [0x7fc571ebcd00] DEBUG - Streams didn't change in analysis, not saving.
Feb 04, 2019 00:04:43.592 [0x7fc571ebcd00] DEBUG - Updating media item 10261, size=1668535213, metadata_item_id=8255
Feb 04, 2019 00:04:43.592 [0x7fc571ebcd00] DEBUG - Updating part with ID=10318 [/mnt/wd/4t/Plex/Movies/Mash - The Movie (1970).m4v]
Feb 04, 2019 00:04:44.774 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8255&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:44.776 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8255&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:44.776 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8255&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:44.778 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8255&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:44.778 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8255&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:44.780 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8255&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:44.803 [0x7fc571ebcd00] DEBUG - Analyzing media parts for item 8262 (Noah): 10268
Feb 04, 2019 00:04:44.803 [0x7fc571ebcd00] DEBUG - [ID 10325] Media part analysis: /mnt/wd/4t/Plex/Movies/Noah (2014).mkv
Feb 04, 2019 00:04:44.805 [0x7fc571ebcd00] DEBUG - [MI] Opening input file: "/mnt/wd/4t/Plex/Movies/Noah (2014).mkv"
Feb 04, 2019 00:04:44.805 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Opening '/mnt/wd/4t/Plex/Movies/Noah (2014).mkv' for reading
Feb 04, 2019 00:04:44.805 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Feb 04, 2019 00:04:44.901 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Feb 04, 2019 00:04:44.914 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:44.914 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:44.914 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:44.914 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 835 bytes read:65536 seeks:2 nb_streams:3
Feb 04, 2019 00:04:44.914 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:44.914 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:44.915 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:44.915 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:44.915 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Feb 04, 2019 00:04:44.915 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Feb 04, 2019 00:04:44.915 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Feb 04, 2019 00:04:44.918 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:44.920 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:44.922 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:44.925 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:44.931 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:44.937 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:44.944 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:44.947 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:44.951 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:44.956 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:44.960 [0x7fc571ebcd00] DEBUG - [FFMPEG] - All info found
Feb 04, 2019 00:04:44.960 [0x7fc571ebcd00] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 28566 bytes read:98304 seeks:2 frames:36
Feb 04, 2019 00:04:44.961 [0x7fc571ebcd00] DEBUG - [MI]  * 7fc00a304e387c1c093a66be6998fb68fb2ef57d [mkv h264/ac3] Duration: 8273198ms, 1920x1040 (1.846154) 23.976fps, bitrate=9533kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/wd/4t/Plex/Movies/Noah (2014).mkv")
Feb 04, 2019 00:04:44.961 [0x7fc571ebcd00] DEBUG - [MI] Importing 22 media chapters.
Feb 04, 2019 00:04:44.962 [0x7fc571ebcd00] DEBUG - [MI] Chapters had generic titles, clearing.
Feb 04, 2019 00:04:44.964 [0x7fc571ebcd00] DEBUG - [MI] Closing input file: "/mnt/wd/4t/Plex/Movies/Noah (2014).mkv"
Feb 04, 2019 00:04:44.966 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Feb 04, 2019 00:04:44.967 [0x7fc571ebcd00] DEBUG - Committing media stream changes for part 10325
Feb 04, 2019 00:04:44.988 [0x7fc571ebcd00] DEBUG - Updating media item 10268, size=9858675826, metadata_item_id=8262
Feb 04, 2019 00:04:44.988 [0x7fc571ebcd00] DEBUG - Updating part with ID=10325 [/mnt/wd/4t/Plex/Movies/Noah (2014).mkv]
Feb 04, 2019 00:04:45.006 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8262&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:45.008 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8262&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:45.008 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8262&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:45.010 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8262&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:45.011 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8262&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:45.012 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8262&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:45.019 [0x7fc571ebcd00] DEBUG - It took 10.000000 ms to retrieve 147 items.
Feb 04, 2019 00:04:45.032 [0x7fc571ebcd00] DEBUG - Analyzing media parts for item 4076 (Spider-Man): 10555
Feb 04, 2019 00:04:45.033 [0x7fc571ebcd00] DEBUG - [ID 10612] Media part analysis: /mnt/wd/4t/Plex/Movies/Marvel/Spider Man (2002).mp4
Feb 04, 2019 00:04:45.051 [0x7fc571ebcd00] DEBUG - [MI] Opening input file: "/mnt/wd/4t/Plex/Movies/Marvel/Spider Man (2002).mp4"
Feb 04, 2019 00:04:45.051 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Opening '/mnt/wd/4t/Plex/Movies/Marvel/Spider Man (2002).mp4' for reading
Feb 04, 2019 00:04:45.051 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Feb 04, 2019 00:04:45.079 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Feb 04, 2019 00:04:45.079 [0x7fc571ebcd00] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Feb 04, 2019 00:04:45.095 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:45.201 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: -1, duration: 984
Feb 04, 2019 00:04:45.201 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 0, edit list 1 - media time: 0, duration: 196252056
Feb 04, 2019 00:04:45.210 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:45.337 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 0, duration: 392505360
Feb 04, 2019 00:04:45.359 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 4305418783 bytes read:5765100 seeks:1 nb_streams:2
Feb 04, 2019 00:04:45.359 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:45.359 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:45.361 [0x7fc571ebcd00] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Feb 04, 2019 00:04:45.361 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Feb 04, 2019 00:04:45.361 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Feb 04, 2019 00:04:45.361 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Feb 04, 2019 00:04:45.365 [0x7fc571ebcd00] DEBUG - [FFMPEG] - All info found
Feb 04, 2019 00:04:45.365 [0x7fc571ebcd00] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 20666 bytes read:5797868 seeks:2 frames:3
Feb 04, 2019 00:04:45.365 [0x7fc571ebcd00] DEBUG - [MI] Opening "/mnt/wd/4t/Plex/Movies/Marvel/Spider Man (2002).mp4" for MP4 atom parsing
Feb 04, 2019 00:04:45.365 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Feb 04, 2019 00:04:45.365 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping mdat atom of size 4299686419 at offset 32
Feb 04, 2019 00:04:45.365 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found moov atom of size 5732332 at offset 4299686451
Feb 04, 2019 00:04:45.369 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 2418580 at offset 4299686567
Feb 04, 2019 00:04:45.369 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 3313540 at offset 4302105147
Feb 04, 2019 00:04:45.369 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 2418432 at offset 4299686715
Feb 04, 2019 00:04:45.369 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 2418347 at offset 4299686800
Feb 04, 2019 00:04:45.369 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 2418283 at offset 4299686864
Feb 04, 2019 00:04:45.369 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found co64 atom of size 1568464 at offset 4300536683
Feb 04, 2019 00:04:45.369 [0x7fc571ebcd00] DEBUG - [MI] File has co64 atom with size 1568464 at offset 4300536683
Feb 04, 2019 00:04:45.369 [0x7fc571ebcd00] DEBUG - [MI]  * b563fadbf6c139aa5e98228e5395c8a2553f0f53 [mp4 h264/aac] Duration: 8177195ms, 1280x720 (1.777778) 23.976fps, bitrate=4212kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/wd/4t/Plex/Movies/Marvel/Spider Man (2002).mp4")
Feb 04, 2019 00:04:45.370 [0x7fc571ebcd00] DEBUG - [MI] Chapters had generic titles, clearing.
Feb 04, 2019 00:04:45.370 [0x7fc571ebcd00] DEBUG - [MI] Closing input file: "/mnt/wd/4t/Plex/Movies/Marvel/Spider Man (2002).mp4"
Feb 04, 2019 00:04:45.371 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Statistics: 5797868 bytes read, 2 seeks
Feb 04, 2019 00:04:45.371 [0x7fc571ebcd00] DEBUG - Streams didn't change in analysis, not saving.
Feb 04, 2019 00:04:45.372 [0x7fc571ebcd00] DEBUG - Updating media item 10555, size=4305418783, metadata_item_id=4076
Feb 04, 2019 00:04:45.621 [0x7fc571ebcd00] DEBUG - Updating part with ID=10612 [/mnt/wd/4t/Plex/Movies/Marvel/Spider Man (2002).mp4]
Feb 04, 2019 00:04:46.607 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4076&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:46.608 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4076&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:46.608 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4076&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:46.610 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4076&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:46.610 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4076&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:46.612 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4076&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:46.640 [0x7fc571ebcd00] DEBUG - It took 20.000000 ms to retrieve 138 items.
Feb 04, 2019 00:04:46.652 [0x7fc571ebcd00] DEBUG - Analyzing media parts for item 8397 (Mamma Mia!): 10403
Feb 04, 2019 00:04:46.652 [0x7fc571ebcd00] DEBUG - [ID 10460] Media part analysis: /mnt/wd/4t/Plex/Movies/Downloads/Momma Mia! 2008.mp4
Feb 04, 2019 00:04:46.666 [0x7fc571ebcd00] DEBUG - [MI] Opening input file: "/mnt/wd/4t/Plex/Movies/Downloads/Momma Mia! 2008.mp4"
Feb 04, 2019 00:04:46.666 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Opening '/mnt/wd/4t/Plex/Movies/Downloads/Momma Mia! 2008.mp4' for reading
Feb 04, 2019 00:04:46.666 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Feb 04, 2019 00:04:46.670 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Feb 04, 2019 00:04:46.670 [0x7fc571ebcd00] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Feb 04, 2019 00:04:46.756 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:46.821 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 3840, duration: 300071040
Feb 04, 2019 00:04:46.832 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Offset DTS by 3840 to make first pts zero.
Feb 04, 2019 00:04:46.859 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting codecpar->delay to 1 for stream st: 0
Feb 04, 2019 00:04:46.859 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:46.910 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:46.911 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 2, edit list 0 - media time: 3840, duration: 300071040
Feb 04, 2019 00:04:46.911 [0x7fc571ebcd00] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Feb 04, 2019 00:04:47.132 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1073117193 bytes read:3318883 seeks:22 nb_streams:3
Feb 04, 2019 00:04:47.132 [0x7fc571ebcd00] DEBUG - [FFMPEG] - NALFF: Consumed only 20 bytes instead of 28
Feb 04, 2019 00:04:47.132 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:47.132 [0x7fc571ebcd00] ERROR - [FFMPEG] - Invalid NAL unit 0, skipping.
Feb 04, 2019 00:04:47.132 [0x7fc571ebcd00] ERROR - [FFMPEG] - time_scale/num_units_in_tick invalid or unsupported (0/1)
Feb 04, 2019 00:04:47.132 [0x7fc571ebcd00] ERROR - [FFMPEG] - Overread VUI by 8 bits
Feb 04, 2019 00:04:47.132 [0x7fc571ebcd00] WARN - [FFMPEG] - SPS decoding failure, trying again after escaping the NAL
Feb 04, 2019 00:04:47.132 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:47.132 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:47.133 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Feb 04, 2019 00:04:47.133 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Feb 04, 2019 00:04:47.133 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Feb 04, 2019 00:04:47.133 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:47.134 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:47.134 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:47.135 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:47.136 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:47.137 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:47.137 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:47.138 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:47.139 [0x7fc571ebcd00] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Feb 04, 2019 00:04:47.139 [0x7fc571ebcd00] DEBUG - [FFMPEG] - All info found
Feb 04, 2019 00:04:47.139 [0x7fc571ebcd00] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 1298 bytes read:3351651 seeks:23 frames:26
Feb 04, 2019 00:04:47.139 [0x7fc571ebcd00] DEBUG - [MI] Opening "/mnt/wd/4t/Plex/Movies/Downloads/Momma Mia! 2008.mp4" for MP4 atom parsing
Feb 04, 2019 00:04:47.139 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 24 at offset 0
Feb 04, 2019 00:04:47.139 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping mdat atom of size 1070486414 at offset 24
Feb 04, 2019 00:04:47.139 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found moov atom of size 2630755 at offset 1070486438
Feb 04, 2019 00:04:47.140 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 1432130 at offset 1070486578
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 1197562 at offset 1071918708
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 812 at offset 1073116270
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 1431994 at offset 1070486678
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 1431921 at offset 1070486751
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 1431857 at offset 1070486815
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 1197420 at offset 1071918808
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 1197347 at offset 1071918881
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 1197287 at offset 1071918941
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 1073116250
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 1073116258
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 676 at offset 1073116370
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 603 at offset 1073116443
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 483 at offset 1073116563
Feb 04, 2019 00:04:47.141 [0x7fc571ebcd00] DEBUG - [MI]  * cbd514c9ed61547faebf0a50c7087f1b11e76616 [mp4 h264/aac] Duration: 6251480ms, 720x428 (2.392523) 25.000fps, bitrate=1373kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/wd/4t/Plex/Movies/Downloads/Momma Mia! 2008.mp4")
Feb 04, 2019 00:04:47.142 [0x7fc571ebcd00] DEBUG - [MI] Importing 20 media chapters.
Feb 04, 2019 00:04:47.145 [0x7fc571ebcd00] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:47.146 [0x7fc571ebcd00] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:47.148 [0x7fc571ebcd00] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:47.148 [0x7fc571ebcd00] DEBUG - Updating metadata item (save) (Mamma Mia!) with ID 8397
Feb 04, 2019 00:04:47.622 [0x7fc571ebcd00] DEBUG - [MI] Closing input file: "/mnt/wd/4t/Plex/Movies/Downloads/Momma Mia! 2008.mp4"
Feb 04, 2019 00:04:47.623 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Statistics: 3351651 bytes read, 23 seeks
Feb 04, 2019 00:04:47.623 [0x7fc571ebcd00] DEBUG - Streams didn't change in analysis, not saving.
Feb 04, 2019 00:04:47.624 [0x7fc571ebcd00] DEBUG - Updating media item 10403, size=1073117193, metadata_item_id=8397
Feb 04, 2019 00:04:47.624 [0x7fc571ebcd00] DEBUG - Updating part with ID=10460 [/mnt/wd/4t/Plex/Movies/Downloads/Momma Mia! 2008.mp4]
Feb 04, 2019 00:04:47.660 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8397&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:47.662 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8397&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:47.662 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8397&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:47.664 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8397&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:47.664 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8397&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:47.666 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8397&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:47.694 [0x7fc571ebcd00] DEBUG - It took 10.000000 ms to retrieve 107 items.
Feb 04, 2019 00:04:47.705 [0x7fc571ebcd00] DEBUG - Analyzing media parts for item 8342 (War Horse): 10348
Feb 04, 2019 00:04:47.705 [0x7fc571ebcd00] DEBUG - [ID 10405] Media part analysis: /mnt/wd/4t/Plex/Movies/War Horse (2011).mkv
Feb 04, 2019 00:04:47.738 [0x7fc571ebcd00] DEBUG - [MI] Opening input file: "/mnt/wd/4t/Plex/Movies/War Horse (2011).mkv"
Feb 04, 2019 00:04:47.739 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Opening '/mnt/wd/4t/Plex/Movies/War Horse (2011).mkv' for reading
Feb 04, 2019 00:04:47.739 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Feb 04, 2019 00:04:47.742 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Feb 04, 2019 00:04:47.760 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:47.760 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:47.760 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:47.760 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 834 bytes read:65536 seeks:2 nb_streams:3
Feb 04, 2019 00:04:47.760 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:47.760 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:47.761 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:47.761 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:47.761 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Feb 04, 2019 00:04:47.761 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Feb 04, 2019 00:04:47.761 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Feb 04, 2019 00:04:47.763 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:47.765 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:47.766 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:47.769 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:47.773 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:47.778 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:47.783 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:47.785 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:47.789 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:47.792 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:47.796 [0x7fc571ebcd00] DEBUG - [FFMPEG] - All info found
Feb 04, 2019 00:04:47.796 [0x7fc571ebcd00] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 28366 bytes read:98304 seeks:2 frames:36
Feb 04, 2019 00:04:47.796 [0x7fc571ebcd00] DEBUG - [MI]  * 155dbd5b51c28d46ed823f900826fa920058b1ff [mkv h264/ac3] Duration: 8799866ms, 1920x800 (2.400000) 23.976fps, bitrate=5506kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/wd/4t/Plex/Movies/War Horse (2011).mkv")
Feb 04, 2019 00:04:47.797 [0x7fc571ebcd00] DEBUG - [MI] Importing 27 media chapters.
Feb 04, 2019 00:04:47.797 [0x7fc571ebcd00] DEBUG - [MI] Chapters had generic titles, clearing.
Feb 04, 2019 00:04:47.800 [0x7fc571ebcd00] DEBUG - [MI] Closing input file: "/mnt/wd/4t/Plex/Movies/War Horse (2011).mkv"
Feb 04, 2019 00:04:47.802 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Feb 04, 2019 00:04:47.803 [0x7fc571ebcd00] DEBUG - Committing media stream changes for part 10405
Feb 04, 2019 00:04:47.822 [0x7fc571ebcd00] DEBUG - Updating media item 10348, size=6057461821, metadata_item_id=8342
Feb 04, 2019 00:04:47.841 [0x7fc571ebcd00] DEBUG - Updating part with ID=10405 [/mnt/wd/4t/Plex/Movies/War Horse (2011).mkv]
Feb 04, 2019 00:04:47.872 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8342&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:47.874 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8342&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:47.875 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8342&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:47.877 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8342&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:47.877 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8342&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:47.879 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8342&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:47.888 [0x7fc571ebcd00] DEBUG - It took 20.000000 ms to retrieve 147 items.
Feb 04, 2019 00:04:47.902 [0x7fc571ebcd00] DEBUG - Analyzing media parts for item 8373 (Deadpool 2): 10379
Feb 04, 2019 00:04:47.902 [0x7fc571ebcd00] DEBUG - [ID 10436] Media part analysis: /mnt/wd/4t/Plex/Movies/Downloads/Deadpool.2.2018.Super.Duper.Cut.1080p.HDRip.X264.AC3-EVO.mkv
Feb 04, 2019 00:04:47.921 [0x7fc571ebcd00] DEBUG - [MI] Opening input file: "/mnt/wd/4t/Plex/Movies/Downloads/Deadpool.2.2018.Super.Duper.Cut.1080p.HDRip.X264.AC3-EVO.mkv"
Feb 04, 2019 00:04:47.921 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Opening '/mnt/wd/4t/Plex/Movies/Downloads/Deadpool.2.2018.Super.Duper.Cut.1080p.HDRip.X264.AC3-EVO.mkv' for reading
Feb 04, 2019 00:04:47.921 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Feb 04, 2019 00:04:47.942 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Feb 04, 2019 00:04:47.957 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:47.957 [0x7fc571ebcd00] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Feb 04, 2019 00:04:47.957 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 9416 bytes read:33574 seeks:2 nb_streams:2
Feb 04, 2019 00:04:47.957 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:47.957 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:47.958 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:47.958 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:47.958 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Feb 04, 2019 00:04:47.958 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Feb 04, 2019 00:04:47.958 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Feb 04, 2019 00:04:47.960 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:47.975 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:47.977 [0x7fc571ebcd00] DEBUG - [FFMPEG] - no picture 
Feb 04, 2019 00:04:48.016 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:48.020 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:48.026 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:48.029 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:48.034 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:48.037 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Feb 04, 2019 00:04:48.041 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Feb 04, 2019 00:04:48.044 [0x7fc571ebcd00] DEBUG - [FFMPEG] - All info found
Feb 04, 2019 00:04:48.044 [0x7fc571ebcd00] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 351053 bytes read:394508 seeks:2 frames:26
Feb 04, 2019 00:04:48.045 [0x7fc571ebcd00] DEBUG - [MI]  * 6f8235f97378a4ca9d2872233ce5f0adea5f3d67 [mkv h264/ac3] Duration: 8040638ms, 1920x800 (2.400000) 23.976fps, bitrate=4726kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/wd/4t/Plex/Movies/Downloads/Deadpool.2.2018.Super.Duper.Cut.1080p.HDRip.X264.AC3-EVO.mkv")
Feb 04, 2019 00:04:48.045 [0x7fc571ebcd00] DEBUG - [MI] Importing 37 media chapters.
Feb 04, 2019 00:04:48.051 [0x7fc571ebcd00] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:48.051 [0x7fc571ebcd00] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:48.054 [0x7fc571ebcd00] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Feb 04, 2019 00:04:48.054 [0x7fc571ebcd00] DEBUG - Updating metadata item (save) (Deadpool 2) with ID 8373
Feb 04, 2019 00:04:48.152 [0x7fc571ebcd00] DEBUG - [MI] Closing input file: "/mnt/wd/4t/Plex/Movies/Downloads/Deadpool.2.2018.Super.Duper.Cut.1080p.HDRip.X264.AC3-EVO.mkv"
Feb 04, 2019 00:04:48.155 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Statistics: 394508 bytes read, 2 seeks
Feb 04, 2019 00:04:48.155 [0x7fc571ebcd00] DEBUG - Streams didn't change in analysis, not saving.
Feb 04, 2019 00:04:48.156 [0x7fc571ebcd00] DEBUG - Updating media item 10379, size=4750230679, metadata_item_id=8373
Feb 04, 2019 00:04:48.172 [0x7fc571ebcd00] DEBUG - Updating part with ID=10436 [/mnt/wd/4t/Plex/Movies/Downloads/Deadpool.2.2018.Super.Duper.Cut.1080p.HDRip.X264.AC3-EVO.mkv]
Feb 04, 2019 00:04:48.190 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8373&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:48.191 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8373&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:48.192 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8373&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:48.193 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8373&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:48.194 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8373&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:48.195 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=8373&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:48.201 [0x7fc571ebcd00] DEBUG - It took 0.000000 ms to retrieve 130 items.
Feb 04, 2019 00:04:48.217 [0x7fc571ebcd00] DEBUG - Analyzing media parts for item 4061 (Ant-Man): 10538
Feb 04, 2019 00:04:48.217 [0x7fc571ebcd00] DEBUG - [ID 10595] Media part analysis: /mnt/wd/4t/Plex/Movies/Marvel/Ant Man (2015).mp4
Feb 04, 2019 00:04:48.228 [0x7fc571ebcd00] DEBUG - [MI] Opening input file: "/mnt/wd/4t/Plex/Movies/Marvel/Ant Man (2015).mp4"
Feb 04, 2019 00:04:48.228 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Opening '/mnt/wd/4t/Plex/Movies/Marvel/Ant Man (2015).mp4' for reading
Feb 04, 2019 00:04:48.228 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Feb 04, 2019 00:04:48.248 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Feb 04, 2019 00:04:48.248 [0x7fc571ebcd00] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Feb 04, 2019 00:04:48.267 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:48.336 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: -1, duration: 984
Feb 04, 2019 00:04:48.336 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 0, edit list 1 - media time: 0, duration: 168639480
Feb 04, 2019 00:04:48.344 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:48.437 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 0, duration: 337280016
Feb 04, 2019 00:04:48.458 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 3725564417 bytes read:3610952 seeks:1 nb_streams:2
Feb 04, 2019 00:04:48.458 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:48.458 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:48.459 [0x7fc571ebcd00] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Feb 04, 2019 00:04:48.460 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Feb 04, 2019 00:04:48.460 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Feb 04, 2019 00:04:48.460 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Feb 04, 2019 00:04:48.462 [0x7fc571ebcd00] DEBUG - [FFMPEG] - All info found
Feb 04, 2019 00:04:48.462 [0x7fc571ebcd00] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3459 bytes read:3643720 seeks:2 frames:3
Feb 04, 2019 00:04:48.462 [0x7fc571ebcd00] DEBUG - [MI] Opening "/mnt/wd/4t/Plex/Movies/Marvel/Ant Man (2015).mp4" for MP4 atom parsing
Feb 04, 2019 00:04:48.462 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Feb 04, 2019 00:04:48.462 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Feb 04, 2019 00:04:48.462 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping mdat atom of size 3721986193 at offset 40
Feb 04, 2019 00:04:48.462 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found moov atom of size 3578184 at offset 3721986233
Feb 04, 2019 00:04:48.464 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 1404484 at offset 3721986349
Feb 04, 2019 00:04:48.464 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 2173488 at offset 3723390833
Feb 04, 2019 00:04:48.464 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 1404336 at offset 3721986497
Feb 04, 2019 00:04:48.464 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 1404251 at offset 3721986582
Feb 04, 2019 00:04:48.464 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 1404187 at offset 3721986646
Feb 04, 2019 00:04:48.464 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 2173352 at offset 3723390969
Feb 04, 2019 00:04:48.464 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 2173267 at offset 3723391054
Feb 04, 2019 00:04:48.464 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 2173207 at offset 3723391114
Feb 04, 2019 00:04:48.465 [0x7fc571ebcd00] DEBUG - [MI]  * 9befa00feb340330421474ce4ac96dd1de4c9617 [mp4 h264/aac] Duration: 7026667ms, 1280x720 (1.777778) 23.976fps, bitrate=4241kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/wd/4t/Plex/Movies/Marvel/Ant Man (2015).mp4")
Feb 04, 2019 00:04:48.466 [0x7fc571ebcd00] DEBUG - [MI] Chapters had generic titles, clearing.
Feb 04, 2019 00:04:48.466 [0x7fc571ebcd00] DEBUG - [MI] Closing input file: "/mnt/wd/4t/Plex/Movies/Marvel/Ant Man (2015).mp4"
Feb 04, 2019 00:04:48.467 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Statistics: 3643720 bytes read, 2 seeks
Feb 04, 2019 00:04:48.467 [0x7fc571ebcd00] DEBUG - Streams didn't change in analysis, not saving.
Feb 04, 2019 00:04:48.467 [0x7fc571ebcd00] DEBUG - Updating media item 10538, size=3725564417, metadata_item_id=4061
Feb 04, 2019 00:04:48.468 [0x7fc571ebcd00] DEBUG - Updating part with ID=10595 [/mnt/wd/4t/Plex/Movies/Marvel/Ant Man (2015).mp4]
Feb 04, 2019 00:04:48.517 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4061&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:48.518 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4061&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:48.519 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4061&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:48.521 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4061&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:48.521 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4061&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:48.523 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4061&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:48.531 [0x7fc571ebcd00] DEBUG - It took 10.000000 ms to retrieve 170 items.
Feb 04, 2019 00:04:48.545 [0x7fc571ebcd00] DEBUG - Analyzing media parts for item 4065 (Captain America: Civil War): 10542
Feb 04, 2019 00:04:48.545 [0x7fc571ebcd00] DEBUG - [ID 10599] Media part analysis: /mnt/wd/4t/Plex/Movies/Marvel/Captain America - Civil War (2016).mp4
Feb 04, 2019 00:04:48.546 [0x7fc571ebcd00] DEBUG - [MI] Opening input file: "/mnt/wd/4t/Plex/Movies/Marvel/Captain America - Civil War (2016).mp4"
Feb 04, 2019 00:04:48.547 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Opening '/mnt/wd/4t/Plex/Movies/Marvel/Captain America - Civil War (2016).mp4' for reading
Feb 04, 2019 00:04:48.547 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Feb 04, 2019 00:04:48.553 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Feb 04, 2019 00:04:48.553 [0x7fc571ebcd00] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Feb 04, 2019 00:04:48.570 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:48.685 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: -1, duration: 984
Feb 04, 2019 00:04:48.685 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 0, edit list 1 - media time: 0, duration: 212686488
Feb 04, 2019 00:04:48.695 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Feb 04, 2019 00:04:48.835 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 0, duration: 425374752
Feb 04, 2019 00:04:48.859 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 4671191466 bytes read:6245020 seeks:1 nb_streams:2
Feb 04, 2019 00:04:48.859 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Feb 04, 2019 00:04:48.859 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Feb 04, 2019 00:04:48.861 [0x7fc571ebcd00] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Feb 04, 2019 00:04:48.861 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Feb 04, 2019 00:04:48.861 [0x7fc571ebcd00] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Feb 04, 2019 00:04:48.861 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Feb 04, 2019 00:04:48.866 [0x7fc571ebcd00] DEBUG - [FFMPEG] - All info found
Feb 04, 2019 00:04:48.866 [0x7fc571ebcd00] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 30704 bytes read:6277788 seeks:2 frames:3
Feb 04, 2019 00:04:48.866 [0x7fc571ebcd00] DEBUG - [MI] Opening "/mnt/wd/4t/Plex/Movies/Marvel/Captain America - Civil War (2016).mp4" for MP4 atom parsing
Feb 04, 2019 00:04:48.866 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Feb 04, 2019 00:04:48.866 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Skipping mdat atom of size 4664979182 at offset 32
Feb 04, 2019 00:04:48.866 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found moov atom of size 6212252 at offset 4664979214
Feb 04, 2019 00:04:48.870 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 2621072 at offset 4664979330
Feb 04, 2019 00:04:48.870 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found trak atom of size 3590968 at offset 4667600402
Feb 04, 2019 00:04:48.870 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found mdia atom of size 2620924 at offset 4664979478
Feb 04, 2019 00:04:48.870 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found minf atom of size 2620839 at offset 4664979563
Feb 04, 2019 00:04:48.870 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found stbl atom of size 2620775 at offset 4664979627
Feb 04, 2019 00:04:48.870 [0x7fc571ebcd00] DEBUG - Mp4AtomParser: Found co64 atom of size 1699808 at offset 4665900594
Feb 04, 2019 00:04:48.870 [0x7fc571ebcd00] DEBUG - [MI] File has co64 atom with size 1699808 at offset 4665900594
Feb 04, 2019 00:04:48.871 [0x7fc571ebcd00] DEBUG - [MI]  * 21b616b615011c7b73b2494a7b23544f40854f32 [mp4 h264/aac] Duration: 8861974ms, 1280x720 (1.777778) 23.976fps, bitrate=4216kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/wd/4t/Plex/Movies/Marvel/Captain America - Civil War (2016).mp4")
Feb 04, 2019 00:04:48.872 [0x7fc571ebcd00] DEBUG - [MI] Chapters had generic titles, clearing.
Feb 04, 2019 00:04:48.872 [0x7fc571ebcd00] DEBUG - [MI] Closing input file: "/mnt/wd/4t/Plex/Movies/Marvel/Captain America - Civil War (2016).mp4"
Feb 04, 2019 00:04:48.873 [0x7fc571ebcd00] DEBUG - [FFMPEG] - Statistics: 6277788 bytes read, 2 seeks
Feb 04, 2019 00:04:48.873 [0x7fc571ebcd00] DEBUG - Streams didn't change in analysis, not saving.
Feb 04, 2019 00:04:48.873 [0x7fc571ebcd00] DEBUG - Updating media item 10542, size=4671191466, metadata_item_id=4065
Feb 04, 2019 00:04:48.874 [0x7fc571ebcd00] DEBUG - Updating part with ID=10599 [/mnt/wd/4t/Plex/Movies/Marvel/Captain America - Civil War (2016).mp4]
Feb 04, 2019 00:04:49.794 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4065&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:49.796 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4065&metadataType=1&state=-1&mediaState=analyzing
Feb 04, 2019 00:04:49.796 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4065&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:49.798 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4065&metadataType=1&state=-1&mediaState=thumbnailing
Feb 04, 2019 00:04:49.798 [0x7fc5613ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4065&metadataType=1&state=-1&mediaState=idle
Feb 04, 2019 00:04:49.800 [0x7fc5613ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=4065&metadataType=1&state=-1&mediaState=idle
