Aug 09, 2018 02:12:23.352 [0x700000104000] INFO - Plex Media Scanner v1.13.5.5291-6fa5e50a8 - Apple Mac  - build: darwin-x86_64 - GMT -04:00
Aug 09, 2018 02:12:23.353 [0x700000104000] INFO - MacOSX version: 10.11.6, language: en
Aug 09, 2018 02:12:23.353 [0x700000104000] INFO - Processor Intel(R) Core(TM)2 Duo CPU     P7550  @ 2.26GHz
Aug 09, 2018 02:12:23.353 [0x700000104000] INFO - /Applications/Plex Media Server.app/Contents/MacOS/Plex Media Scanner --analyze --item 1911,1909,1887,3291,3289,1885,1905,3231,1898,1900 --log-file-suffix  Analysis
Aug 09, 2018 02:12:23.356 [0x7fff72f30000] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Aug 09, 2018 02:12:23.605 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1911 (Survivor): 4243
Aug 09, 2018 02:12:23.605 [0x7fff72f30000] DEBUG - [ID 4244] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv
Aug 09, 2018 02:12:23.605 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv"
Aug 09, 2018 02:12:23.605 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv' for reading
Aug 09, 2018 02:12:23.606 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 09, 2018 02:12:30.806 [0x7fff72f30000] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 09, 2018 02:12:30.822 [0x7fff72f30000] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:30.822 [0x7fff72f30000] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:30.822 [0x7fff72f30000] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:30.822 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5674 bytes read:34108 seeks:2 nb_streams:3
Aug 09, 2018 02:12:30.823 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:30.823 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:30.948 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:30.948 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:30.949 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:30.949 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 09, 2018 02:12:30.949 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 09, 2018 02:12:30.951 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:30.953 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:30.956 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:30.960 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:30.964 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:30.968 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:30.969 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:30.972 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:30.974 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 09, 2018 02:12:30.974 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 48128 bytes read:99644 seeks:2 frames:41
Aug 09, 2018 02:12:30.975 [0x7fff72f30000] DEBUG - [MI]  * eac0977d505bf658d1ba83e84d985f6a4310f54d [mkv h264/aac] Duration: 5787973ms, 1280x536 (2.388060) 23.976fps, bitrate=2000kbps, audio channels=2, optimizedForStreaming=0 ("/Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv")
Aug 09, 2018 02:12:30.976 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 09, 2018 02:12:30.976 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv"
Aug 09, 2018 02:12:30.977 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 99644 bytes read, 2 seeks
Aug 09, 2018 02:12:30.979 [0x7fff72f30000] DEBUG - Updating media item 4243, size=1447255332, metadata_item_id=1911
Aug 09, 2018 02:12:30.979 [0x7fff72f30000] DEBUG - Updating part with ID=4244 [/Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv]
Aug 09, 2018 02:12:30.982 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1911&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:30.991 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1911&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:30.992 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1911&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:30.996 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1911&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:30.996 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1911&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:31.000 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1911&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:31.014 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1909 (Seven Psychopaths): 4241
Aug 09, 2018 02:12:31.014 [0x7fff72f30000] DEBUG - [ID 4242] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4
Aug 09, 2018 02:12:31.014 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4"
Aug 09, 2018 02:12:31.014 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4' for reading
Aug 09, 2018 02:12:31.015 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 09, 2018 02:12:31.015 [0x7fff72f30000] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 09, 2018 02:12:31.015 [0x7fff72f30000] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Aug 09, 2018 02:12:31.015 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 09, 2018 02:12:31.137 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Aug 09, 2018 02:12:31.137 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 09, 2018 02:12:31.216 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 787315208 bytes read:3413219 seeks:1 nb_streams:2
Aug 09, 2018 02:12:31.217 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:31.217 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:31.218 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.218 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 09, 2018 02:12:31.218 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 09, 2018 02:12:31.219 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:31.221 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.222 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:31.223 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.226 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.230 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.233 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.235 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.237 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.241 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.244 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 09, 2018 02:12:31.244 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3406066 bytes read:3445987 seeks:2 frames:13
Aug 09, 2018 02:12:31.244 [0x7fff72f30000] DEBUG - [MI] Opening "/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4" for MP4 atom parsing
Aug 09, 2018 02:12:31.263 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 24 at offset 0
Aug 09, 2018 02:12:31.268 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found moov atom of size 3404352 at offset 24
Aug 09, 2018 02:12:31.272 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 1958902 at offset 161
Aug 09, 2018 02:12:31.272 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 1445313 at offset 1959063
Aug 09, 2018 02:12:31.272 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 1958802 at offset 261
Aug 09, 2018 02:12:31.272 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 1958729 at offset 334
Aug 09, 2018 02:12:31.272 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 1958665 at offset 398
Aug 09, 2018 02:12:31.272 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 1445213 at offset 1959163
Aug 09, 2018 02:12:31.272 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 1445118 at offset 1959258
Aug 09, 2018 02:12:31.272 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 1445058 at offset 1959318
Aug 09, 2018 02:12:31.273 [0x7fff72f30000] DEBUG - [MI]  * dfec6d4f2e673108d97f419a6471c83d2edb2e46 [mp4 h264/aac] Duration: 6623617ms, 1280x528 (2.424242) 23.976fps, bitrate=950kbps, audio channels=2, optimizedForStreaming=1 ("/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4")
Aug 09, 2018 02:12:31.274 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 09, 2018 02:12:31.274 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4"
Aug 09, 2018 02:12:31.279 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 3445987 bytes read, 2 seeks
Aug 09, 2018 02:12:31.279 [0x7fff72f30000] DEBUG - Streams didn't change in analysis, not saving.
Aug 09, 2018 02:12:31.280 [0x7fff72f30000] DEBUG - Updating media item 4241, size=787315239, metadata_item_id=1909
Aug 09, 2018 02:12:31.280 [0x7fff72f30000] DEBUG - Updating part with ID=4242 [/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4]
Aug 09, 2018 02:12:31.282 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1909&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:31.286 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1909&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:31.286 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1909&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:31.290 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1909&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:31.290 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1909&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:31.294 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1909&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:31.299 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1887 (Brawl in Cell Block 99): 4219
Aug 09, 2018 02:12:31.299 [0x7fff72f30000] DEBUG - [ID 4220] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv
Aug 09, 2018 02:12:31.299 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv"
Aug 09, 2018 02:12:31.299 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv' for reading
Aug 09, 2018 02:12:31.299 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 09, 2018 02:12:31.315 [0x7fff72f30000] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 09, 2018 02:12:31.339 [0x7fff72f30000] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:31.339 [0x7fff72f30000] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:31.340 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5588 bytes read:33582 seeks:2 nb_streams:2
Aug 09, 2018 02:12:31.340 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:31.340 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:31.342 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:31.342 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:31.342 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.343 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 09, 2018 02:12:31.343 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 09, 2018 02:12:31.345 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:31.347 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.349 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:31.351 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.355 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.360 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.365 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.366 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.369 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.372 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.375 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 09, 2018 02:12:31.375 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 31572 bytes read:66350 seeks:2 frames:26
Aug 09, 2018 02:12:31.375 [0x7fff72f30000] DEBUG - [MI]  * f79bce5dbc43195820f38f8e91464226994548df [mkv h264/ac3] Duration: 7952450ms, 1280x690 (1.855072) 23.976fps, bitrate=3072kbps, audio channels=6, optimizedForStreaming=0 ("/Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv")
Aug 09, 2018 02:12:31.376 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 09, 2018 02:12:31.376 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv"
Aug 09, 2018 02:12:31.378 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 66350 bytes read, 2 seeks
Aug 09, 2018 02:12:31.380 [0x7fff72f30000] DEBUG - Updating media item 4219, size=3054495448, metadata_item_id=1887
Aug 09, 2018 02:12:31.380 [0x7fff72f30000] DEBUG - Updating part with ID=4220 [/Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv]
Aug 09, 2018 02:12:31.382 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1887&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:31.386 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1887&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:31.386 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1887&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:31.388 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1887&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:31.389 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1887&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:31.391 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1887&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:31.413 [0x7fff72f30000] DEBUG - Analyzing media parts for item 3291 (Submergence): 7977
Aug 09, 2018 02:12:31.413 [0x7fff72f30000] DEBUG - [ID 7979] Media part analysis: /Volumes/MY PASSPORT/New Movies/Submergence.mkv
Aug 09, 2018 02:12:31.413 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/New Movies/Submergence.mkv"
Aug 09, 2018 02:12:31.413 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/New Movies/Submergence.mkv' for reading
Aug 09, 2018 02:12:31.413 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 09, 2018 02:12:31.435 [0x7fff72f30000] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 09, 2018 02:12:31.472 [0x7fff72f30000] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:31.472 [0x7fff72f30000] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:31.472 [0x7fff72f30000] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:31.473 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5698 bytes read:34055 seeks:2 nb_streams:3
Aug 09, 2018 02:12:31.473 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:31.473 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:31.475 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:31.476 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:31.476 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.476 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 09, 2018 02:12:31.476 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 09, 2018 02:12:31.485 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.493 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.497 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.500 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.507 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.514 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.519 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 09, 2018 02:12:31.519 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 46016 bytes read:99591 seeks:2 frames:48
Aug 09, 2018 02:12:31.519 [0x7fff72f30000] DEBUG - [MI]  * 57f4875139c05238a217e54ec504a82c56d84b0d [mkv h264/ac3] Duration: 6698624ms, 1920x1034 (1.856866) 23.976fps, bitrate=4797kbps, audio channels=6, optimizedForStreaming=0 ("/Volumes/MY PASSPORT/New Movies/Submergence.mkv")
Aug 09, 2018 02:12:31.520 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 09, 2018 02:12:31.520 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/New Movies/Submergence.mkv"
Aug 09, 2018 02:12:31.522 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 99591 bytes read, 2 seeks
Aug 09, 2018 02:12:31.523 [0x7fff72f30000] DEBUG - Committing media stream changes for part 7979
Aug 09, 2018 02:12:31.528 [0x7fff72f30000] DEBUG - Updating media item 7977, size=4017418144, metadata_item_id=3291
Aug 09, 2018 02:12:31.528 [0x7fff72f30000] DEBUG - Updating part with ID=7979 [/Volumes/MY PASSPORT/New Movies/Submergence.mkv]
Aug 09, 2018 02:12:31.530 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3291&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:31.536 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3291&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:31.537 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3291&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:31.540 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3291&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:31.540 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3291&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:31.543 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3291&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:31.549 [0x7fff72f30000] DEBUG - It took 20.000000 ms to retrieve 170 items.
Aug 09, 2018 02:12:31.562 [0x7fff72f30000] DEBUG - Analyzing media parts for item 3289 (Iron Man 3): 7975
Aug 09, 2018 02:12:31.562 [0x7fff72f30000] DEBUG - [ID 7977] Media part analysis: /Volumes/MY PASSPORT/New Movies/Iron Man 3.mp4
Aug 09, 2018 02:12:31.562 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/New Movies/Iron Man 3.mp4"
Aug 09, 2018 02:12:31.562 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/New Movies/Iron Man 3.mp4' for reading
Aug 09, 2018 02:12:31.562 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 09, 2018 02:12:31.563 [0x7fff72f30000] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 09, 2018 02:12:31.563 [0x7fff72f30000] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Aug 09, 2018 02:12:31.563 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 09, 2018 02:12:31.731 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Aug 09, 2018 02:12:31.731 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 09, 2018 02:12:31.801 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2095937551 bytes read:4056765 seeks:1 nb_streams:2
Aug 09, 2018 02:12:31.802 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:31.802 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:31.803 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.803 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.803 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 09, 2018 02:12:31.803 [0x7fff72f30000] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Aug 09, 2018 02:12:31.803 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 09, 2018 02:12:31.806 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:31.809 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.809 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.809 [0x7fff72f30000] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Aug 09, 2018 02:12:31.813 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:31.815 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.816 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.816 [0x7fff72f30000] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Aug 09, 2018 02:12:31.823 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.823 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.823 [0x7fff72f30000] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Aug 09, 2018 02:12:31.831 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.831 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.831 [0x7fff72f30000] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Aug 09, 2018 02:12:31.840 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.840 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.840 [0x7fff72f30000] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Aug 09, 2018 02:12:31.843 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.843 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:31.843 [0x7fff72f30000] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Aug 09, 2018 02:12:31.847 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.848 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.848 [0x7fff72f30000] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Aug 09, 2018 02:12:31.852 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:31.852 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:31.852 [0x7fff72f30000] DEBUG - [FFMPEG] - ct_type:0 pic_struct:0
Aug 09, 2018 02:12:31.860 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 09, 2018 02:12:31.860 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 4028656 bytes read:4089533 seeks:2 frames:13
Aug 09, 2018 02:12:31.861 [0x7fff72f30000] DEBUG - [MI] Opening "/Volumes/MY PASSPORT/New Movies/Iron Man 3.mp4" for MP4 atom parsing
Aug 09, 2018 02:12:31.861 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 24 at offset 0
Aug 09, 2018 02:12:31.861 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found moov atom of size 4026690 at offset 24
Aug 09, 2018 02:12:31.866 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 2286848 at offset 161
Aug 09, 2018 02:12:31.866 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 1739705 at offset 2287009
Aug 09, 2018 02:12:31.866 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 2286748 at offset 261
Aug 09, 2018 02:12:31.866 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 2286614 at offset 395
Aug 09, 2018 02:12:31.866 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 2286550 at offset 459
Aug 09, 2018 02:12:31.866 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 1739605 at offset 2287109
Aug 09, 2018 02:12:31.866 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 1739510 at offset 2287204
Aug 09, 2018 02:12:31.866 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 1739450 at offset 2287264
Aug 09, 2018 02:12:31.867 [0x7fff72f30000] DEBUG - [MI]  * 38f4ea2c001097a0c4fa6bc12482e055a090bb95 [mp4 h264/aac] Duration: 7832938ms, 1920x800 (2.400000) 23.976fps, bitrate=2140kbps, audio channels=2, optimizedForStreaming=1 ("/Volumes/MY PASSPORT/New Movies/Iron Man 3.mp4")
Aug 09, 2018 02:12:31.868 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 09, 2018 02:12:31.868 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/New Movies/Iron Man 3.mp4"
Aug 09, 2018 02:12:31.877 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 4089533 bytes read, 2 seeks
Aug 09, 2018 02:12:31.879 [0x7fff72f30000] DEBUG - Updating media item 7975, size=2095937578, metadata_item_id=3289
Aug 09, 2018 02:12:31.880 [0x7fff72f30000] DEBUG - Updating part with ID=7977 [/Volumes/MY PASSPORT/New Movies/Iron Man 3.mp4]
Aug 09, 2018 02:12:31.881 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3289&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:31.885 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3289&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:31.886 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3289&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:31.890 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3289&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:31.890 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3289&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:31.894 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=3289&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:31.897 [0x7fff72f30000] DEBUG - It took 10.000000 ms to retrieve 129 items.
Aug 09, 2018 02:12:31.908 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1885 (Anna Karenina): 4217
Aug 09, 2018 02:12:31.908 [0x7fff72f30000] DEBUG - [ID 4218] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi
Aug 09, 2018 02:12:31.909 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi"
Aug 09, 2018 02:12:31.909 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi' for reading
Aug 09, 2018 02:12:31.909 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 09, 2018 02:12:31.925 [0x7fff72f30000] DEBUG - [FFMPEG] - Format avi probed with size=2048 and score=100
Aug 09, 2018 02:12:31.925 [0x7fff72f30000] DEBUG - [FFMPEG] - use odml:1
Aug 09, 2018 02:12:31.925 [0x7fff72f30000] DEBUG - [FFMPEG] - st:0 removing common factor 1080000 from timebase
Aug 09, 2018 02:12:32.197 [0x7fff72f30000] INFO - [FFMPEG] - non-interleaved AVI
Aug 09, 2018 02:12:32.198 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2060 bytes read:8089136 seeks:4 nb_streams:2
Aug 09, 2018 02:12:32.224 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 09, 2018 02:12:32.225 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 09, 2018 02:12:32.225 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 4380 bytes read:8089136 seeks:4 frames:2
Aug 09, 2018 02:12:32.225 [0x7fff72f30000] DEBUG - [MI]  * 6c9d5e45c63855f7a84c57a069ec8332a3078b22 [avi mpeg4/mp3] Duration: 7460720ms, 640x272 (2.352941) 25.000fps, bitrate=794kbps, audio channels=2, optimizedForStreaming=0 ("/Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi")
Aug 09, 2018 02:12:32.226 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 09, 2018 02:12:32.226 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi"
Aug 09, 2018 02:12:32.228 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 8089136 bytes read, 4 seeks
Aug 09, 2018 02:12:32.230 [0x7fff72f30000] DEBUG - Updating media item 4217, size=741047092, metadata_item_id=1885
Aug 09, 2018 02:12:32.230 [0x7fff72f30000] DEBUG - Updating part with ID=4218 [/Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi]
Aug 09, 2018 02:12:32.232 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1885&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:32.236 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1885&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:32.236 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1885&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:32.240 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1885&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:32.241 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1885&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:32.244 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1885&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:32.260 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1905 (Our Kind of Traitor): 4237
Aug 09, 2018 02:12:32.260 [0x7fff72f30000] DEBUG - [ID 4238] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4
Aug 09, 2018 02:12:32.260 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4"
Aug 09, 2018 02:12:32.260 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4' for reading
Aug 09, 2018 02:12:32.260 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 09, 2018 02:12:32.261 [0x7fff72f30000] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 09, 2018 02:12:32.261 [0x7fff72f30000] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Aug 09, 2018 02:12:32.261 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 09, 2018 02:12:32.347 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 2000, duration: 154945000
Aug 09, 2018 02:12:32.361 [0x7fff72f30000] DEBUG - [FFMPEG] - Offset DTS by 2000 to make first pts zero.
Aug 09, 2018 02:12:32.396 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Aug 09, 2018 02:12:32.396 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 09, 2018 02:12:32.420 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 1024, duration: 309932000
Aug 09, 2018 02:12:32.420 [0x7fff72f30000] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Aug 09, 2018 02:12:32.437 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 846723921 bytes read:3247365 seeks:1 nb_streams:2
Aug 09, 2018 02:12:32.437 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:32.437 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:32.438 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:32.438 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 09, 2018 02:12:32.438 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 09, 2018 02:12:32.440 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:32.441 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:32.443 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:32.444 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:32.447 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:32.450 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:32.454 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:32.455 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:32.457 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:32.459 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:32.462 [0x7fff72f30000] DEBUG - [FFMPEG] - demuxer injecting skip 1024 / discard 0
Aug 09, 2018 02:12:32.462 [0x7fff72f30000] DEBUG - [FFMPEG] - skip 1024 / discard 0 samples due to side data
Aug 09, 2018 02:12:32.462 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 09, 2018 02:12:32.462 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3235817 bytes read:3280133 seeks:2 frames:14
Aug 09, 2018 02:12:32.463 [0x7fff72f30000] DEBUG - [MI] Opening "/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4" for MP4 atom parsing
Aug 09, 2018 02:12:32.463 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 20 at offset 0
Aug 09, 2018 02:12:32.463 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found moov atom of size 3234391 at offset 20
Aug 09, 2018 02:12:32.467 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 1970458 at offset 157
Aug 09, 2018 02:12:32.467 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 1263796 at offset 1970615
Aug 09, 2018 02:12:32.467 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 1970322 at offset 293
Aug 09, 2018 02:12:32.467 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 1970249 at offset 366
Aug 09, 2018 02:12:32.467 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 1970185 at offset 430
Aug 09, 2018 02:12:32.467 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 1263660 at offset 1970751
Aug 09, 2018 02:12:32.467 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 1263587 at offset 1970824
Aug 09, 2018 02:12:32.467 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 1263527 at offset 1970884
Aug 09, 2018 02:12:32.468 [0x7fff72f30000] DEBUG - [MI]  * 35f1cf0abb6e227c4dc16b7ed0894e5bb6c75b56 [mp4 h264/aac] Duration: 6456917ms, 1280x538 (2.379182) 24.000fps, bitrate=1049kbps, audio channels=2, optimizedForStreaming=1 ("/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4")
Aug 09, 2018 02:12:32.469 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 09, 2018 02:12:32.469 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4"
Aug 09, 2018 02:12:32.475 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 3280133 bytes read, 2 seeks
Aug 09, 2018 02:12:32.477 [0x7fff72f30000] DEBUG - Updating media item 4237, size=846723971, metadata_item_id=1905
Aug 09, 2018 02:12:32.478 [0x7fff72f30000] DEBUG - Updating part with ID=4238 [/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4]
Aug 09, 2018 02:12:32.479 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1905&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:32.483 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1905&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:32.483 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1905&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:32.486 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1905&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:32.487 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1905&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:32.490 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1905&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:32.501 [0x7fff72f30000] DEBUG - Analyzing media parts for item 3231 (The Light Between Oceans): 7821
Aug 09, 2018 02:12:32.501 [0x7fff72f30000] DEBUG - [ID 7823] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi
Aug 09, 2018 02:12:32.501 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi"
Aug 09, 2018 02:12:32.502 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi' for reading
Aug 09, 2018 02:12:32.502 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 09, 2018 02:12:32.521 [0x7fff72f30000] DEBUG - [FFMPEG] - Format avi probed with size=2048 and score=100
Aug 09, 2018 02:12:32.521 [0x7fff72f30000] DEBUG - [FFMPEG] - use odml:1
Aug 09, 2018 02:12:33.161 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 65536 bytes read:4088032 seeks:61 nb_streams:2
Aug 09, 2018 02:12:33.162 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 09, 2018 02:12:33.163 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 09, 2018 02:12:33.163 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 78747 bytes read:4088032 seeks:61 frames:7
Aug 09, 2018 02:12:33.163 [0x7fff72f30000] DEBUG - [MI]  * 0df063212e6b6dc89e254c6454d413b6132366d5 [avi mpeg4/ac3] Duration: 7971415ms, 720x304 (2.368421) 23.976fps, bitrate=1533kbps, audio channels=6, optimizedForStreaming=0 ("/Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi")
Aug 09, 2018 02:12:33.164 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 09, 2018 02:12:33.164 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi"
Aug 09, 2018 02:12:33.165 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 4088032 bytes read, 61 seeks
Aug 09, 2018 02:12:33.167 [0x7fff72f30000] DEBUG - Updating media item 7821, size=1527680150, metadata_item_id=3231
Aug 09, 2018 02:12:33.167 [0x7fff72f30000] DEBUG - Updating part with ID=7823 [/Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi]
Aug 09, 2018 02:12:33.169 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=3231&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:33.172 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=3231&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:33.173 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=3231&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:33.177 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=3231&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:33.177 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=3231&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:33.180 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=3231&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:33.200 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1898 (Inferno): 4230
Aug 09, 2018 02:12:33.200 [0x7fff72f30000] DEBUG - [ID 4231] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4
Aug 09, 2018 02:12:33.200 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4"
Aug 09, 2018 02:12:33.200 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4' for reading
Aug 09, 2018 02:12:33.201 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 09, 2018 02:12:33.201 [0x7fff72f30000] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 09, 2018 02:12:33.201 [0x7fff72f30000] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Aug 09, 2018 02:12:33.217 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 09, 2018 02:12:33.339 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 3750, duration: 657843750
Aug 09, 2018 02:12:33.356 [0x7fff72f30000] DEBUG - [FFMPEG] - Offset DTS by 3750 to make first pts zero.
Aug 09, 2018 02:12:33.396 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting codecpar->delay to 1 for stream st: 0
Aug 09, 2018 02:12:33.396 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 09, 2018 02:12:33.484 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 09, 2018 02:12:33.516 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 2, edit list 0 - media time: 256, duration: 350083296
Aug 09, 2018 02:12:33.516 [0x7fff72f30000] DEBUG - [FFMPEG] - skip 256 audio samples from curr_cts: 0
Aug 09, 2018 02:12:33.530 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1961102718 bytes read:5957897 seeks:1 nb_streams:3
Aug 09, 2018 02:12:33.530 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:33.530 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:33.532 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:33.532 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 09, 2018 02:12:33.532 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 09, 2018 02:12:33.533 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:33.534 [0x7fff72f30000] DEBUG - [FFMPEG] - demuxer injecting skip 256 / discard 0
Aug 09, 2018 02:12:33.534 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:33.536 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 09, 2018 02:12:33.537 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3855 bytes read:5990665 seeks:2 frames:4
Aug 09, 2018 02:12:33.537 [0x7fff72f30000] DEBUG - [MI] Opening "/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4" for MP4 atom parsing
Aug 09, 2018 02:12:33.537 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Aug 09, 2018 02:12:33.537 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Aug 09, 2018 02:12:33.537 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping mdat atom of size 1955177549 at offset 40
Aug 09, 2018 02:12:33.537 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found moov atom of size 5925129 at offset 1955177589
Aug 09, 2018 02:12:33.542 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 2277222 at offset 1955177729
Aug 09, 2018 02:12:33.542 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 2735500 at offset 1957454951
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 912156 at offset 1960190451
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 2277086 at offset 1955177865
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 2277001 at offset 1955177950
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 2276937 at offset 1955178014
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 2735378 at offset 1957455051
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 2735299 at offset 1957455130
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 2735239 at offset 1957455190
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 911976 at offset 1960190607
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 911895 at offset 1960190688
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 911835 at offset 1960190748
Aug 09, 2018 02:12:33.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 1960190587
Aug 09, 2018 02:12:33.544 [0x7fff72f30000] DEBUG - [MI]  * e8a31d752777c0b9d64f3ecf81adaffc5f1fb18a [mp4 h264/ac3] Duration: 7309375ms, 1008x544 (1.852713) 24.000fps, bitrate=2146kbps, audio channels=6, optimizedForStreaming=0 ("/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4")
Aug 09, 2018 02:12:33.545 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 09, 2018 02:12:33.545 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4"
Aug 09, 2018 02:12:33.550 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 5990665 bytes read, 2 seeks
Aug 09, 2018 02:12:33.552 [0x7fff72f30000] DEBUG - Updating media item 4230, size=1961102718, metadata_item_id=1898
Aug 09, 2018 02:12:33.552 [0x7fff72f30000] DEBUG - Updating part with ID=4231 [/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4]
Aug 09, 2018 02:12:33.554 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1898&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:33.558 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1898&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:33.558 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1898&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:33.562 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1898&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:33.562 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1898&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:33.565 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1898&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:33.570 [0x7fff72f30000] DEBUG - It took 20.000000 ms to retrieve 134 items.
Aug 09, 2018 02:12:33.579 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1900 (It): 4232
Aug 09, 2018 02:12:33.579 [0x7fff72f30000] DEBUG - [ID 4233] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv
Aug 09, 2018 02:12:33.580 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv"
Aug 09, 2018 02:12:33.580 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv' for reading
Aug 09, 2018 02:12:33.580 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 09, 2018 02:12:33.612 [0x7fff72f30000] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 09, 2018 02:12:33.612 [0x7fff72f30000] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:33.612 [0x7fff72f30000] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:33.612 [0x7fff72f30000] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 09, 2018 02:12:33.613 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 845 bytes read:32768 seeks:0 nb_streams:3
Aug 09, 2018 02:12:33.613 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:33.613 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:33.614 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 09, 2018 02:12:33.615 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 09, 2018 02:12:33.615 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 09, 2018 02:12:33.615 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 09, 2018 02:12:33.615 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 09, 2018 02:12:33.616 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 09, 2018 02:12:33.617 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:33.619 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:33.623 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:33.627 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:33.629 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 09, 2018 02:12:33.630 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:33.633 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 09, 2018 02:12:33.636 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 09, 2018 02:12:33.636 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 27017 bytes read:32768 seeks:0 frames:32
Aug 09, 2018 02:12:33.636 [0x7fff72f30000] DEBUG - [MI]  * b301081e2dffe1b48ebce19d49d8202cd233b097 [mkv h264/aac] Duration: 7751130ms, 968x546 (1.779874) 25.000fps, bitrate=1974kbps, audio channels=2, optimizedForStreaming=0 ("/Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv")
Aug 09, 2018 02:12:33.637 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 09, 2018 02:12:33.637 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv"
Aug 09, 2018 02:12:33.642 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Aug 09, 2018 02:12:33.643 [0x7fff72f30000] DEBUG - Committing media stream changes for part 4233
Aug 09, 2018 02:12:33.645 [0x7fff72f30000] DEBUG - Updating media item 4232, size=1913478536, metadata_item_id=1900
Aug 09, 2018 02:12:33.645 [0x7fff72f30000] DEBUG - Updating part with ID=4233 [/Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv]
Aug 09, 2018 02:12:33.647 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1900&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:33.649 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1900&metadataType=1&state=-1&mediaState=analyzing
Aug 09, 2018 02:12:33.650 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1900&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:33.652 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1900&metadataType=1&state=-1&mediaState=thumbnailing
Aug 09, 2018 02:12:33.652 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1900&metadataType=1&state=-1&mediaState=idle
Aug 09, 2018 02:12:33.654 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=1900&metadataType=1&state=-1&mediaState=idle
