Aug 12, 2018 02:09:21.723 [0x700000104000] INFO - Plex Media Scanner v1.13.5.5291-6fa5e50a8 - Apple Mac  - build: darwin-x86_64 - GMT -04:00
Aug 12, 2018 02:09:21.724 [0x700000104000] INFO - MacOSX version: 10.11.6, language: en
Aug 12, 2018 02:09:21.724 [0x700000104000] INFO - Processor Intel(R) Core(TM)2 Duo CPU     P7550  @ 2.26GHz
Aug 12, 2018 02:09:21.724 [0x700000104000] INFO - /Applications/Plex Media Server.app/Contents/MacOS/Plex Media Scanner --analyze --item 2956,1887,1900,1898,1909,1885,1905,1911,3231 --log-file-suffix  Analysis
Aug 12, 2018 02:09:21.727 [0x7fff72f30000] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Aug 12, 2018 02:09:21.960 [0x7fff72f30000] DEBUG - It took 10.000000 ms to retrieve 131 items.
Aug 12, 2018 02:09:21.979 [0x7fff72f30000] DEBUG - Analyzing media parts for item 2956 (Guardians of the Galaxy Vol. 2): 7109
Aug 12, 2018 02:09:21.979 [0x7fff72f30000] DEBUG - [ID 7110] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Guardians of the Galaxy Vol. 2.mp4
Aug 12, 2018 02:09:22.232 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Guardians of the Galaxy Vol. 2.mp4"
Aug 12, 2018 02:09:22.232 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Guardians of the Galaxy Vol. 2.mp4' for reading
Aug 12, 2018 02:09:22.232 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 12, 2018 02:09:28.347 [0x7fff72f30000] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 12, 2018 02:09:28.347 [0x7fff72f30000] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Aug 12, 2018 02:09:28.361 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 12, 2018 02:09:28.495 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 7507, duration: 733156200
Aug 12, 2018 02:09:28.512 [0x7fff72f30000] DEBUG - [FFMPEG] - Offset DTS by 7507 to make first pts zero.
Aug 12, 2018 02:09:28.557 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Aug 12, 2018 02:09:28.557 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 12, 2018 02:09:28.583 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: -1, duration: 960
Aug 12, 2018 02:09:28.583 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 1, edit list 1 - media time: 256, duration: 391016160
Aug 12, 2018 02:09:28.583 [0x7fff72f30000] DEBUG - [FFMPEG] - skip 256 audio samples from curr_cts: 0
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 23.916667 0.004905
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 23.916667 0.004905
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 24.000000 0.000800
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 24.000000 0.000800
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 24.083333 0.016031
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 24.083333 0.016031
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 48.000000 0.003201
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 48.000000 0.003201
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 23.976024 0.000000
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 23.976024 0.000000
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 47.952048 0.000000
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - rfps: 47.952048 0.000000
Aug 12, 2018 02:09:28.596 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 3386046179 bytes read:4931895 seeks:2 nb_streams:2
Aug 12, 2018 02:09:28.597 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 12, 2018 02:09:28.597 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 12, 2018 02:09:28.601 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 12, 2018 02:09:28.601 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 12, 2018 02:09:28.601 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 12, 2018 02:09:28.604 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:28.607 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:28.610 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:28.613 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:28.621 [0x7fff72f30000] DEBUG - [FFMPEG] - demuxer injecting skip 256 / discard 0
Aug 12, 2018 02:09:28.621 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 12, 2018 02:09:28.621 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3815 bytes read:4964663 seeks:3 frames:4
Aug 12, 2018 02:09:28.621 [0x7fff72f30000] DEBUG - [MI] Opening "/Volumes/MY PASSPORT/Unwatched Movies/Guardians of the Galaxy Vol. 2.mp4" for MP4 atom parsing
Aug 12, 2018 02:09:28.697 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Aug 12, 2018 02:09:28.701 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Aug 12, 2018 02:09:28.701 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping mdat atom of size 3380978917 at offset 40
Aug 12, 2018 02:09:28.701 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found moov atom of size 5067222 at offset 3380978957
Aug 12, 2018 02:09:28.706 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 4048227 at offset 3380979097
Aug 12, 2018 02:09:28.706 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 1018744 at offset 3385027324
Aug 12, 2018 02:09:28.707 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 4048091 at offset 3380979233
Aug 12, 2018 02:09:28.707 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 4048006 at offset 3380979318
Aug 12, 2018 02:09:28.707 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 4047942 at offset 3380979382
Aug 12, 2018 02:09:28.707 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 1018572 at offset 3385027472
Aug 12, 2018 02:09:28.707 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 1018491 at offset 3385027553
Aug 12, 2018 02:09:28.707 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 1018431 at offset 3385027613
Aug 12, 2018 02:09:28.707 [0x7fff72f30000] DEBUG - [MI]  * 213734173eedce1ef082ca6a6749b5da5daee328 [mp4 h264/ac3] Duration: 8146180ms, 1910x804 (2.375622) 23.976fps, bitrate=3325kbps, audio channels=6, optimizedForStreaming=0 ("/Volumes/MY PASSPORT/Unwatched Movies/Guardians of the Galaxy Vol. 2.mp4")
Aug 12, 2018 02:09:28.709 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 12, 2018 02:09:28.709 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Guardians of the Galaxy Vol. 2.mp4"
Aug 12, 2018 02:09:28.714 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 4964663 bytes read, 3 seeks
Aug 12, 2018 02:09:28.716 [0x7fff72f30000] DEBUG - Updating media item 7109, size=3386046179, metadata_item_id=2956
Aug 12, 2018 02:09:28.716 [0x7fff72f30000] DEBUG - Updating part with ID=7110 [/Volumes/MY PASSPORT/Unwatched Movies/Guardians of the Galaxy Vol. 2.mp4]
Aug 12, 2018 02:09:28.718 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=2956&metadataType=1&state=-1&mediaState=analyzing
Aug 12, 2018 02:09:28.729 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=2956&metadataType=1&state=-1&mediaState=analyzing
Aug 12, 2018 02:09:28.729 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=2956&metadataType=1&state=-1&mediaState=thumbnailing
Aug 12, 2018 02:09:28.733 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=2956&metadataType=1&state=-1&mediaState=thumbnailing
Aug 12, 2018 02:09:28.734 [0x700000323000] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=2956&metadataType=1&state=-1&mediaState=idle
Aug 12, 2018 02:09:28.736 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1887 (Brawl in Cell Block 99): 4219
Aug 12, 2018 02:09:28.736 [0x7fff72f30000] DEBUG - [ID 4220] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv
Aug 12, 2018 02:09:28.736 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv"
Aug 12, 2018 02:09:28.736 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv' for reading
Aug 12, 2018 02:09:28.736 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 12, 2018 02:09:28.737 [0x700000323000] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=9&metadataItemID=2956&metadataType=1&state=-1&mediaState=idle
Aug 12, 2018 02:09:28.756 [0x7fff72f30000] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 12, 2018 02:09:28.781 [0x7fff72f30000] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 12, 2018 02:09:28.781 [0x7fff72f30000] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 12, 2018 02:09:28.781 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5588 bytes read:33582 seeks:2 nb_streams:2
Aug 12, 2018 02:09:28.782 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 12, 2018 02:09:28.782 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 12, 2018 02:09:28.783 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 12, 2018 02:09:28.784 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 12, 2018 02:09:28.784 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 12, 2018 02:09:28.784 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 12, 2018 02:09:28.784 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 12, 2018 02:09:28.786 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:28.787 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:28.788 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:28.790 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:28.794 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:28.799 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:28.803 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:28.805 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:28.807 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:28.810 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:28.813 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 12, 2018 02:09:28.813 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 31572 bytes read:66350 seeks:2 frames:26
Aug 12, 2018 02:09:28.813 [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 12, 2018 02:09:28.814 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 12, 2018 02:09:28.814 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv"
Aug 12, 2018 02:09:28.817 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 66350 bytes read, 2 seeks
Aug 12, 2018 02:09:28.819 [0x7fff72f30000] DEBUG - Updating media item 4219, size=3054495448, metadata_item_id=1887
Aug 12, 2018 02:09:28.819 [0x7fff72f30000] DEBUG - Updating part with ID=4220 [/Volumes/MY PASSPORT/Unwatched Movies/Brawl In Cell Block 99.mkv]
Aug 12, 2018 02:09:28.821 [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 12, 2018 02:09:28.826 [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 12, 2018 02:09:28.827 [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 12, 2018 02:09:28.830 [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 12, 2018 02:09:28.830 [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 12, 2018 02:09:28.834 [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 12, 2018 02:09:28.837 [0x7fff72f30000] DEBUG - It took 10.000000 ms to retrieve 134 items.
Aug 12, 2018 02:09:28.846 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1900 (It): 4232
Aug 12, 2018 02:09:28.846 [0x7fff72f30000] DEBUG - [ID 4233] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv
Aug 12, 2018 02:09:28.846 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv"
Aug 12, 2018 02:09:28.846 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv' for reading
Aug 12, 2018 02:09:28.846 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 12, 2018 02:09:28.873 [0x7fff72f30000] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 12, 2018 02:09:28.873 [0x7fff72f30000] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 12, 2018 02:09:28.873 [0x7fff72f30000] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 12, 2018 02:09:28.873 [0x7fff72f30000] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 12, 2018 02:09:28.873 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 845 bytes read:32768 seeks:0 nb_streams:3
Aug 12, 2018 02:09:28.874 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 12, 2018 02:09:28.874 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 12, 2018 02:09:28.877 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 12, 2018 02:09:28.877 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 12, 2018 02:09:28.877 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 12, 2018 02:09:28.877 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 12, 2018 02:09:28.877 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 12, 2018 02:09:28.878 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:28.879 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:28.881 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:28.886 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:28.889 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:28.892 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:28.893 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:28.896 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:28.899 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 12, 2018 02:09:28.899 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 27017 bytes read:32768 seeks:0 frames:32
Aug 12, 2018 02:09:28.899 [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 12, 2018 02:09:28.900 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 12, 2018 02:09:28.900 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv"
Aug 12, 2018 02:09:28.902 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Aug 12, 2018 02:09:28.904 [0x7fff72f30000] DEBUG - Committing media stream changes for part 4233
Aug 12, 2018 02:09:28.906 [0x7fff72f30000] DEBUG - Updating media item 4232, size=1913478536, metadata_item_id=1900
Aug 12, 2018 02:09:28.906 [0x7fff72f30000] DEBUG - Updating part with ID=4233 [/Volumes/MY PASSPORT/Unwatched Movies/It (2017).mkv]
Aug 12, 2018 02:09:28.908 [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 12, 2018 02:09:28.912 [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 12, 2018 02:09:28.912 [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 12, 2018 02:09:28.915 [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 12, 2018 02:09:28.916 [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 12, 2018 02:09:28.918 [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
Aug 12, 2018 02:09:28.939 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1898 (Inferno): 4230
Aug 12, 2018 02:09:28.940 [0x7fff72f30000] DEBUG - [ID 4231] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4
Aug 12, 2018 02:09:28.940 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4"
Aug 12, 2018 02:09:28.940 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4' for reading
Aug 12, 2018 02:09:28.940 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 12, 2018 02:09:28.965 [0x7fff72f30000] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 12, 2018 02:09:28.965 [0x7fff72f30000] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Aug 12, 2018 02:09:28.974 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 12, 2018 02:09:29.058 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 3750, duration: 657843750
Aug 12, 2018 02:09:29.074 [0x7fff72f30000] DEBUG - [FFMPEG] - Offset DTS by 3750 to make first pts zero.
Aug 12, 2018 02:09:29.111 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting codecpar->delay to 1 for stream st: 0
Aug 12, 2018 02:09:29.112 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 12, 2018 02:09:29.239 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 12, 2018 02:09:29.260 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 2, edit list 0 - media time: 256, duration: 350083296
Aug 12, 2018 02:09:29.260 [0x7fff72f30000] DEBUG - [FFMPEG] - skip 256 audio samples from curr_cts: 0
Aug 12, 2018 02:09:29.274 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1961102718 bytes read:5957897 seeks:1 nb_streams:3
Aug 12, 2018 02:09:29.274 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 12, 2018 02:09:29.274 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 12, 2018 02:09:29.276 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 12, 2018 02:09:29.277 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 12, 2018 02:09:29.277 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 12, 2018 02:09:29.277 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:29.278 [0x7fff72f30000] DEBUG - [FFMPEG] - demuxer injecting skip 256 / discard 0
Aug 12, 2018 02:09:29.278 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:29.280 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 12, 2018 02:09:29.280 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3855 bytes read:5990665 seeks:2 frames:4
Aug 12, 2018 02:09:29.280 [0x7fff72f30000] DEBUG - [MI] Opening "/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4" for MP4 atom parsing
Aug 12, 2018 02:09:29.280 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Aug 12, 2018 02:09:29.280 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Aug 12, 2018 02:09:29.281 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping mdat atom of size 1955177549 at offset 40
Aug 12, 2018 02:09:29.281 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found moov atom of size 5925129 at offset 1955177589
Aug 12, 2018 02:09:29.288 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 2277222 at offset 1955177729
Aug 12, 2018 02:09:29.288 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 2735500 at offset 1957454951
Aug 12, 2018 02:09:29.288 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 912156 at offset 1960190451
Aug 12, 2018 02:09:29.288 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 2277086 at offset 1955177865
Aug 12, 2018 02:09:29.288 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 2277001 at offset 1955177950
Aug 12, 2018 02:09:29.288 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 2276937 at offset 1955178014
Aug 12, 2018 02:09:29.289 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 2735378 at offset 1957455051
Aug 12, 2018 02:09:29.289 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 2735299 at offset 1957455130
Aug 12, 2018 02:09:29.289 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 2735239 at offset 1957455190
Aug 12, 2018 02:09:29.289 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 911976 at offset 1960190607
Aug 12, 2018 02:09:29.289 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 911895 at offset 1960190688
Aug 12, 2018 02:09:29.289 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 911835 at offset 1960190748
Aug 12, 2018 02:09:29.289 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 1960190587
Aug 12, 2018 02:09:29.290 [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 12, 2018 02:09:29.291 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 12, 2018 02:09:29.291 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4"
Aug 12, 2018 02:09:29.297 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 5990665 bytes read, 2 seeks
Aug 12, 2018 02:09:29.299 [0x7fff72f30000] DEBUG - Updating media item 4230, size=1961102718, metadata_item_id=1898
Aug 12, 2018 02:09:29.300 [0x7fff72f30000] DEBUG - Updating part with ID=4231 [/Volumes/MY PASSPORT/Unwatched Movies/Inferno.mp4]
Aug 12, 2018 02:09:29.302 [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 12, 2018 02:09:29.306 [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 12, 2018 02:09:29.306 [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 12, 2018 02:09:29.309 [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 12, 2018 02:09:29.310 [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 12, 2018 02:09:29.313 [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 12, 2018 02:09:29.330 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1909 (Seven Psychopaths): 4241
Aug 12, 2018 02:09:29.331 [0x7fff72f30000] DEBUG - [ID 4242] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4
Aug 12, 2018 02:09:29.345 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4"
Aug 12, 2018 02:09:29.345 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4' for reading
Aug 12, 2018 02:09:29.345 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 12, 2018 02:09:29.367 [0x7fff72f30000] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 12, 2018 02:09:29.367 [0x7fff72f30000] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Aug 12, 2018 02:09:29.367 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 12, 2018 02:09:29.470 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Aug 12, 2018 02:09:29.470 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 12, 2018 02:09:29.514 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 787315208 bytes read:3413219 seeks:1 nb_streams:2
Aug 12, 2018 02:09:29.515 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 12, 2018 02:09:29.515 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 12, 2018 02:09:29.516 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 12, 2018 02:09:29.516 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 12, 2018 02:09:29.516 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 12, 2018 02:09:29.518 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:29.519 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:29.520 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:29.521 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:29.525 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:29.528 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:29.532 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:29.534 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:29.536 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:29.540 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:29.543 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 12, 2018 02:09:29.543 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3406066 bytes read:3445987 seeks:2 frames:13
Aug 12, 2018 02:09:29.543 [0x7fff72f30000] DEBUG - [MI] Opening "/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4" for MP4 atom parsing
Aug 12, 2018 02:09:29.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 24 at offset 0
Aug 12, 2018 02:09:29.543 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found moov atom of size 3404352 at offset 24
Aug 12, 2018 02:09:29.547 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 1958902 at offset 161
Aug 12, 2018 02:09:29.547 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 1445313 at offset 1959063
Aug 12, 2018 02:09:29.547 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 1958802 at offset 261
Aug 12, 2018 02:09:29.548 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 1958729 at offset 334
Aug 12, 2018 02:09:29.548 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 1958665 at offset 398
Aug 12, 2018 02:09:29.548 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 1445213 at offset 1959163
Aug 12, 2018 02:09:29.548 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 1445118 at offset 1959258
Aug 12, 2018 02:09:29.548 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 1445058 at offset 1959318
Aug 12, 2018 02:09:29.548 [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 12, 2018 02:09:29.549 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 12, 2018 02:09:29.549 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4"
Aug 12, 2018 02:09:29.557 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 3445987 bytes read, 2 seeks
Aug 12, 2018 02:09:29.557 [0x7fff72f30000] DEBUG - Streams didn't change in analysis, not saving.
Aug 12, 2018 02:09:29.558 [0x7fff72f30000] DEBUG - Updating media item 4241, size=787315239, metadata_item_id=1909
Aug 12, 2018 02:09:29.558 [0x7fff72f30000] DEBUG - Updating part with ID=4242 [/Volumes/MY PASSPORT/Unwatched Movies/Seven Psychopaths.mp4]
Aug 12, 2018 02:09:29.560 [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 12, 2018 02:09:29.563 [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 12, 2018 02:09:29.564 [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 12, 2018 02:09:29.567 [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 12, 2018 02:09:29.567 [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 12, 2018 02:09:29.571 [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 12, 2018 02:09:29.575 [0x7fff72f30000] DEBUG - It took 10.000000 ms to retrieve 129 items.
Aug 12, 2018 02:09:29.585 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1885 (Anna Karenina): 4217
Aug 12, 2018 02:09:29.585 [0x7fff72f30000] DEBUG - [ID 4218] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi
Aug 12, 2018 02:09:29.585 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi"
Aug 12, 2018 02:09:29.585 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi' for reading
Aug 12, 2018 02:09:29.585 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 12, 2018 02:09:29.609 [0x7fff72f30000] DEBUG - [FFMPEG] - Format avi probed with size=2048 and score=100
Aug 12, 2018 02:09:29.609 [0x7fff72f30000] DEBUG - [FFMPEG] - use odml:1
Aug 12, 2018 02:09:29.609 [0x7fff72f30000] DEBUG - [FFMPEG] - st:0 removing common factor 1080000 from timebase
Aug 12, 2018 02:09:29.890 [0x7fff72f30000] INFO - [FFMPEG] - non-interleaved AVI
Aug 12, 2018 02:09:29.890 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2060 bytes read:8089136 seeks:4 nb_streams:2
Aug 12, 2018 02:09:29.917 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 12, 2018 02:09:29.918 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 12, 2018 02:09:29.918 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 4380 bytes read:8089136 seeks:4 frames:2
Aug 12, 2018 02:09:29.918 [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 12, 2018 02:09:29.919 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 12, 2018 02:09:29.919 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi"
Aug 12, 2018 02:09:29.923 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 8089136 bytes read, 4 seeks
Aug 12, 2018 02:09:29.924 [0x7fff72f30000] DEBUG - Updating media item 4217, size=741047092, metadata_item_id=1885
Aug 12, 2018 02:09:29.925 [0x7fff72f30000] DEBUG - Updating part with ID=4218 [/Volumes/MY PASSPORT/Unwatched Movies/Anna Kerenina.avi]
Aug 12, 2018 02:09:29.927 [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 12, 2018 02:09:29.929 [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 12, 2018 02:09:29.929 [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 12, 2018 02:09:29.932 [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 12, 2018 02:09:29.933 [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 12, 2018 02:09:29.935 [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 12, 2018 02:09:29.958 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1905 (Our Kind of Traitor): 4237
Aug 12, 2018 02:09:29.959 [0x7fff72f30000] DEBUG - [ID 4238] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4
Aug 12, 2018 02:09:29.959 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4"
Aug 12, 2018 02:09:29.959 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4' for reading
Aug 12, 2018 02:09:29.959 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 12, 2018 02:09:29.979 [0x7fff72f30000] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 12, 2018 02:09:29.979 [0x7fff72f30000] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Aug 12, 2018 02:09:29.979 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 12, 2018 02:09:30.052 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 2000, duration: 154945000
Aug 12, 2018 02:09:30.066 [0x7fff72f30000] DEBUG - [FFMPEG] - Offset DTS by 2000 to make first pts zero.
Aug 12, 2018 02:09:30.101 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Aug 12, 2018 02:09:30.101 [0x7fff72f30000] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 12, 2018 02:09:30.125 [0x7fff72f30000] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 1024, duration: 309932000
Aug 12, 2018 02:09:30.125 [0x7fff72f30000] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Aug 12, 2018 02:09:30.164 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 846723921 bytes read:3247365 seeks:1 nb_streams:2
Aug 12, 2018 02:09:30.165 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 12, 2018 02:09:30.165 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 12, 2018 02:09:30.166 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 12, 2018 02:09:30.166 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 12, 2018 02:09:30.167 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 12, 2018 02:09:30.167 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:30.169 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:30.170 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:30.171 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:30.174 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:30.177 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:30.180 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:30.182 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:30.184 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:30.186 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:30.189 [0x7fff72f30000] DEBUG - [FFMPEG] - demuxer injecting skip 1024 / discard 0
Aug 12, 2018 02:09:30.189 [0x7fff72f30000] DEBUG - [FFMPEG] - skip 1024 / discard 0 samples due to side data
Aug 12, 2018 02:09:30.189 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 12, 2018 02:09:30.189 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3235817 bytes read:3280133 seeks:2 frames:14
Aug 12, 2018 02:09:30.189 [0x7fff72f30000] DEBUG - [MI] Opening "/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4" for MP4 atom parsing
Aug 12, 2018 02:09:30.190 [0x7fff72f30000] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 20 at offset 0
Aug 12, 2018 02:09:30.190 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found moov atom of size 3234391 at offset 20
Aug 12, 2018 02:09:30.194 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 1970458 at offset 157
Aug 12, 2018 02:09:30.194 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found trak atom of size 1263796 at offset 1970615
Aug 12, 2018 02:09:30.194 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 1970322 at offset 293
Aug 12, 2018 02:09:30.194 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 1970249 at offset 366
Aug 12, 2018 02:09:30.194 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 1970185 at offset 430
Aug 12, 2018 02:09:30.194 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found mdia atom of size 1263660 at offset 1970751
Aug 12, 2018 02:09:30.194 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found minf atom of size 1263587 at offset 1970824
Aug 12, 2018 02:09:30.194 [0x7fff72f30000] DEBUG - Mp4AtomParser: Found stbl atom of size 1263527 at offset 1970884
Aug 12, 2018 02:09:30.194 [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 12, 2018 02:09:30.195 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 12, 2018 02:09:30.195 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4"
Aug 12, 2018 02:09:30.201 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 3280133 bytes read, 2 seeks
Aug 12, 2018 02:09:30.203 [0x7fff72f30000] DEBUG - Updating media item 4237, size=846723971, metadata_item_id=1905
Aug 12, 2018 02:09:30.203 [0x7fff72f30000] DEBUG - Updating part with ID=4238 [/Volumes/MY PASSPORT/Unwatched Movies/Our Kind Of Traitor.mp4]
Aug 12, 2018 02:09:30.205 [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 12, 2018 02:09:30.208 [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 12, 2018 02:09:30.209 [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 12, 2018 02:09:30.211 [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 12, 2018 02:09:30.211 [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 12, 2018 02:09:30.213 [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 12, 2018 02:09:30.228 [0x7fff72f30000] DEBUG - Analyzing media parts for item 1911 (Survivor): 4243
Aug 12, 2018 02:09:30.228 [0x7fff72f30000] DEBUG - [ID 4244] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv
Aug 12, 2018 02:09:30.229 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv"
Aug 12, 2018 02:09:30.229 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv' for reading
Aug 12, 2018 02:09:30.229 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 12, 2018 02:09:30.243 [0x7fff72f30000] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 12, 2018 02:09:30.259 [0x7fff72f30000] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 12, 2018 02:09:30.260 [0x7fff72f30000] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 12, 2018 02:09:30.260 [0x7fff72f30000] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 12, 2018 02:09:30.260 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5674 bytes read:34108 seeks:2 nb_streams:3
Aug 12, 2018 02:09:30.260 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 12, 2018 02:09:30.261 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 12, 2018 02:09:30.263 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 12, 2018 02:09:30.263 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 12, 2018 02:09:30.263 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 12, 2018 02:09:30.264 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 12, 2018 02:09:30.264 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 12, 2018 02:09:30.264 [0x7fff72f30000] DEBUG - [FFMPEG] - no picture 
Aug 12, 2018 02:09:30.266 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:30.267 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:30.271 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:30.275 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:30.279 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 12, 2018 02:09:30.280 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:30.283 [0x7fff72f30000] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 12, 2018 02:09:30.285 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 12, 2018 02:09:30.285 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 48128 bytes read:99644 seeks:2 frames:41
Aug 12, 2018 02:09:30.285 [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 12, 2018 02:09:30.286 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 12, 2018 02:09:30.286 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv"
Aug 12, 2018 02:09:30.288 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 99644 bytes read, 2 seeks
Aug 12, 2018 02:09:30.290 [0x7fff72f30000] DEBUG - Updating media item 4243, size=1447255332, metadata_item_id=1911
Aug 12, 2018 02:09:30.290 [0x7fff72f30000] DEBUG - Updating part with ID=4244 [/Volumes/MY PASSPORT/Unwatched Movies/Survivor (2015).mkv]
Aug 12, 2018 02:09:30.292 [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 12, 2018 02:09:30.294 [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 12, 2018 02:09:30.295 [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 12, 2018 02:09:30.297 [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 12, 2018 02:09:30.297 [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 12, 2018 02:09:30.299 [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 12, 2018 02:09:30.317 [0x7fff72f30000] DEBUG - Analyzing media parts for item 3231 (The Light Between Oceans): 7821
Aug 12, 2018 02:09:30.318 [0x7fff72f30000] DEBUG - [ID 7823] Media part analysis: /Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi
Aug 12, 2018 02:09:30.334 [0x7fff72f30000] DEBUG - [MI] Opening input file: "/Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi"
Aug 12, 2018 02:09:30.334 [0x7fff72f30000] DEBUG - [FFMPEG] - Opening '/Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi' for reading
Aug 12, 2018 02:09:30.334 [0x7fff72f30000] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 12, 2018 02:09:30.355 [0x7fff72f30000] DEBUG - [FFMPEG] - Format avi probed with size=2048 and score=100
Aug 12, 2018 02:09:30.355 [0x7fff72f30000] DEBUG - [FFMPEG] - use odml:1
Aug 12, 2018 02:09:30.986 [0x7fff72f30000] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 65536 bytes read:4088032 seeks:61 nb_streams:2
Aug 12, 2018 02:09:30.987 [0x7fff72f30000] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 12, 2018 02:09:30.987 [0x7fff72f30000] DEBUG - [FFMPEG] - All info found
Aug 12, 2018 02:09:30.987 [0x7fff72f30000] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 78747 bytes read:4088032 seeks:61 frames:7
Aug 12, 2018 02:09:30.987 [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 12, 2018 02:09:30.988 [0x7fff72f30000] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 12, 2018 02:09:30.989 [0x7fff72f30000] DEBUG - [MI] Closing input file: "/Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi"
Aug 12, 2018 02:09:30.990 [0x7fff72f30000] DEBUG - [FFMPEG] - Statistics: 4088032 bytes read, 61 seeks
Aug 12, 2018 02:09:30.992 [0x7fff72f30000] DEBUG - Updating media item 7821, size=1527680150, metadata_item_id=3231
Aug 12, 2018 02:09:30.992 [0x7fff72f30000] DEBUG - Updating part with ID=7823 [/Volumes/MY PASSPORT/Unwatched Movies/The Light Between Oceans.avi]
Aug 12, 2018 02:09:30.994 [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 12, 2018 02:09:30.996 [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 12, 2018 02:09:30.996 [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 12, 2018 02:09:30.998 [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 12, 2018 02:09:30.998 [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 12, 2018 02:09:31.001 [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
