Mar 04, 2019 02:02:21.816 [0688] INFO - Plex Media Scanner v1.14.1.5488-cc260c476 - Microsoft PC x64 - build: windows-i386 - GMT -05:00
Mar 04, 2019 02:02:21.817 [0688] INFO - Windows version: 6.1 (Build 7601), language en-US
Mar 04, 2019 02:02:21.817 [0688] INFO - 4 3192 MHz processor(s): Architecture=0, Level=6, Revision=14857 Processor Identifier=Intel64 Family 6 Model 58 Stepping 9, GenuineIntel
Mar 04, 2019 02:02:21.817 [0688] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Scanner.exe" --analyze --item 28165,177 --log-file-suffix " Analysis" 
Mar 04, 2019 02:02:21.816 [8480] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Mar 04, 2019 02:02:21.949 [8480] DEBUG - Analyzing media parts for item 28165 (Star Wars: Episode II - Attack of the Clones): 28026
Mar 04, 2019 02:02:21.951 [8480] DEBUG - [ID 28052] Media part analysis: U:\Movies2\Attack Of The Clones.m4v
Mar 04, 2019 02:02:21.951 [8480] DEBUG - [MI] Opening input file: "U:\Movies2\Attack Of The Clones.m4v"
Mar 04, 2019 02:02:21.951 [8480] DEBUG - [FFMPEG] - Opening 'U:\Movies2\Attack Of The Clones.m4v' for reading
Mar 04, 2019 02:02:21.951 [8480] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Mar 04, 2019 02:02:21.979 [8480] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Mar 04, 2019 02:02:21.980 [8480] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Mar 04, 2019 02:02:21.980 [8480] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Mar 04, 2019 02:02:21.980 [8480] ERROR - [FFMPEG] - moov atom not found
Mar 04, 2019 02:02:21.980 [8480] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 3 seeks
Mar 04, 2019 02:02:21.980 [8480] ERROR - Exception analyzing media file 'U:\Movies2\Attack Of The Clones.m4v' (Could not parse U:\Movies2\Attack Of The Clones.m4v (error=-1094995529): Invalid data found when processing input)
Mar 04, 2019 02:02:21.980 [8480] DEBUG - Streams didn't change in analysis, not saving.
Mar 04, 2019 02:02:21.981 [8480] DEBUG - Updating media item 28026, size=3302013453, metadata_item_id=28165
Mar 04, 2019 02:02:21.981 [8480] DEBUG - Updating part with ID=28052 [U:\Movies2\Attack Of The Clones.m4v]
Mar 04, 2019 02:02:21.988 [8480] DEBUG - [MI] Opening input file: "U:\Movies2\Attack Of The Clones.m4v"
Mar 04, 2019 02:02:21.988 [8480] DEBUG - [FFMPEG] - Opening 'U:\Movies2\Attack Of The Clones.m4v' for reading
Mar 04, 2019 02:02:21.988 [8480] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Mar 04, 2019 02:02:21.989 [8480] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Mar 04, 2019 02:02:21.989 [8480] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Mar 04, 2019 02:02:21.989 [8480] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Mar 04, 2019 02:02:21.989 [8480] ERROR - [FFMPEG] - moov atom not found
Mar 04, 2019 02:02:21.989 [8480] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 3 seeks
Mar 04, 2019 02:02:21.990 [8480] ERROR - Exception extracting thumbnail from media file 'U:\Movies2\Attack Of The Clones.m4v' (Could not parse U:\Movies2\Attack Of The Clones.m4v (error=-1094995529): Invalid data found when processing input)
Mar 04, 2019 02:02:21.990 [8480] DEBUG - [MI] Opening input file: "U:\Movies2\Attack Of The Clones.m4v"
Mar 04, 2019 02:02:21.990 [8480] DEBUG - [FFMPEG] - Opening 'U:\Movies2\Attack Of The Clones.m4v' for reading
Mar 04, 2019 02:02:21.990 [8480] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Mar 04, 2019 02:02:21.991 [8480] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Mar 04, 2019 02:02:21.991 [8480] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Mar 04, 2019 02:02:21.991 [8480] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Mar 04, 2019 02:02:21.991 [8480] ERROR - [FFMPEG] - moov atom not found
Mar 04, 2019 02:02:21.991 [8480] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 3 seeks
Mar 04, 2019 02:02:21.992 [8480] ERROR - Exception extracting thumbnail from media file 'U:\Movies2\Attack Of The Clones.m4v' (Could not parse U:\Movies2\Attack Of The Clones.m4v (error=-1094995529): Invalid data found when processing input)
Mar 04, 2019 02:02:21.992 [8480] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Mar 04, 2019 02:02:21.993 [8480] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Mar 04, 2019 02:02:21.995 [8480] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Mar 04, 2019 02:02:21.996 [8480] DEBUG - Updating metadata item (save) (Star Wars: Episode II - Attack of the Clones) with ID 28165
Mar 04, 2019 02:02:21.998 [10940] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28165&metadataType=1&state=-1&mediaState=analyzing
Mar 04, 2019 02:02:21.999 [10940] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28165&metadataType=1&state=-1&mediaState=analyzing
Mar 04, 2019 02:02:21.999 [10940] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28165&metadataType=1&state=-1&mediaState=thumbnailing
Mar 04, 2019 02:02:22.001 [10940] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28165&metadataType=1&state=-1&mediaState=thumbnailing
Mar 04, 2019 02:02:22.001 [10940] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28165&metadataType=1&state=-1&mediaState=idle
Mar 04, 2019 02:02:22.002 [10940] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28165&metadataType=1&state=-1&mediaState=idle
Mar 04, 2019 02:02:22.007 [8480] DEBUG - Analyzing media parts for item 177 (Storefront Hitchcock): 177
Mar 04, 2019 02:02:22.008 [8480] DEBUG - [ID 28611] Media part analysis: U:\Movies2\Storefront Hitchcock (1997).m4v
Mar 04, 2019 02:02:22.008 [8480] DEBUG - [MI] Opening input file: "U:\Movies2\Storefront Hitchcock (1997).m4v"
Mar 04, 2019 02:02:22.008 [8480] DEBUG - [FFMPEG] - Opening 'U:\Movies2\Storefront Hitchcock (1997).m4v' for reading
Mar 04, 2019 02:02:22.008 [8480] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Mar 04, 2019 02:02:22.036 [8480] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Mar 04, 2019 02:02:22.036 [8480] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Mar 04, 2019 02:02:22.040 [8480] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Mar 04, 2019 02:02:22.110 [8480] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 15014, duration: 420815070
Mar 04, 2019 02:02:22.118 [8480] DEBUG - [FFMPEG] - Offset DTS by 15014 to make first pts zero.
Mar 04, 2019 02:02:22.134 [8480] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Mar 04, 2019 02:02:22.134 [8480] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Mar 04, 2019 02:02:22.161 [8480] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 1024, duration: 224426976
Mar 04, 2019 02:02:22.162 [8480] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Mar 04, 2019 02:02:22.170 [8480] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 23.916667 0.004926
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 23.916667 0.004926
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 24.000000 0.000806
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 24.000000 0.000806
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 24.083333 0.016116
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 24.083333 0.016116
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 48.000000 0.003222
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 48.000000 0.003222
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 23.976024 0.000000
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 23.976024 0.000000
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 47.952048 0.000000
Mar 04, 2019 02:02:22.171 [8480] DEBUG - [FFMPEG] - rfps: 47.952048 0.000000
Mar 04, 2019 02:02:22.172 [8480] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 489836685 bytes read:4051512 seeks:3 nb_streams:3
Mar 04, 2019 02:02:22.172 [8480] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Mar 04, 2019 02:02:22.172 [8480] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Mar 04, 2019 02:02:22.175 [8480] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Mar 04, 2019 02:02:22.175 [8480] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Mar 04, 2019 02:02:22.175 [8480] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Mar 04, 2019 02:02:22.176 [8480] DEBUG - [FFMPEG] - no picture 
Mar 04, 2019 02:02:22.178 [8480] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Mar 04, 2019 02:02:22.178 [8480] DEBUG - [FFMPEG] - no picture 
Mar 04, 2019 02:02:22.180 [8480] DEBUG - [FFMPEG] - demuxer injecting skip 1024 / discard 0
Mar 04, 2019 02:02:22.180 [8480] DEBUG - [FFMPEG] - skip 1024 / discard 0 samples due to side data
Mar 04, 2019 02:02:22.180 [8480] DEBUG - [FFMPEG] - All info found
Mar 04, 2019 02:02:22.182 [8480] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 24128 bytes read:4084280 seeks:4 frames:3
Mar 04, 2019 02:02:22.182 [8480] DEBUG - [MI] Opening "U:\Movies2\Storefront Hitchcock (1997).m4v" for MP4 atom parsing
Mar 04, 2019 02:02:22.182 [8480] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Mar 04, 2019 02:02:22.183 [8480] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Mar 04, 2019 02:02:22.183 [8480] DEBUG - Mp4AtomParser: Skipping mdat atom of size 485850669 at offset 40
Mar 04, 2019 02:02:22.183 [8480] DEBUG - Mp4AtomParser: Found moov atom of size 3985976 at offset 485850709
Mar 04, 2019 02:02:22.185 [8480] DEBUG - Mp4AtomParser: Found trak atom of size 2231051 at offset 485850849
Mar 04, 2019 02:02:22.185 [8480] DEBUG - Mp4AtomParser: Found trak atom of size 1753863 at offset 488081900
Mar 04, 2019 02:02:22.185 [8480] DEBUG - Mp4AtomParser: Found trak atom of size 811 at offset 489835763
Mar 04, 2019 02:02:22.185 [8480] DEBUG - Mp4AtomParser: Found mdia atom of size 2230895 at offset 485851005
Mar 04, 2019 02:02:22.185 [8480] DEBUG - Mp4AtomParser: Found minf atom of size 2230810 at offset 485851090
Mar 04, 2019 02:02:22.185 [8480] DEBUG - Mp4AtomParser: Found stbl atom of size 2230746 at offset 485851154
Mar 04, 2019 02:02:22.185 [8480] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 485850985
Mar 04, 2019 02:02:22.185 [8480] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 485850993
Mar 04, 2019 02:02:22.185 [8480] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Mar 04, 2019 02:02:22.186 [8480] DEBUG - Mp4AtomParser: Found mdia atom of size 1753685 at offset 488082056
Mar 04, 2019 02:02:22.186 [8480] DEBUG - Mp4AtomParser: Found minf atom of size 1753606 at offset 488082135
Mar 04, 2019 02:02:22.186 [8480] DEBUG - Mp4AtomParser: Found stbl atom of size 1753546 at offset 488082195
Mar 04, 2019 02:02:22.186 [8480] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 488082036
Mar 04, 2019 02:02:22.186 [8480] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 488082044
Mar 04, 2019 02:02:22.186 [8480] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Mar 04, 2019 02:02:22.186 [8480] DEBUG - Mp4AtomParser: Found mdia atom of size 711 at offset 489835863
Mar 04, 2019 02:02:22.186 [8480] DEBUG - Mp4AtomParser: Found minf atom of size 623 at offset 489835951
Mar 04, 2019 02:02:22.186 [8480] DEBUG - Mp4AtomParser: Found stbl atom of size 547 at offset 489836027
Mar 04, 2019 02:02:22.187 [8480] DEBUG - [MI]  * 4e50f9e5b34f52e6cc09555e2f0a9fd23b28b8be [mp4 h264/aac] Duration: 4675723ms, 720x474 (1.346460) 23.970fps, bitrate=838kbps, audio channels=2, optimizedForStreaming=0 ("U:\Movies2\Storefront Hitchcock (1997).m4v")
Mar 04, 2019 02:02:22.187 [8480] DEBUG - [MI] Importing 24 media chapters.
Mar 04, 2019 02:02:22.189 [8480] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Mar 04, 2019 02:02:22.189 [8480] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Mar 04, 2019 02:02:22.191 [8480] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Mar 04, 2019 02:02:22.191 [8480] DEBUG - Updating metadata item (save) (Storefront Hitchcock) with ID 177
Mar 04, 2019 02:02:22.197 [8480] DEBUG - [MI] Closing input file: "U:\Movies2\Storefront Hitchcock (1997).m4v"
Mar 04, 2019 02:02:22.198 [8480] DEBUG - [FFMPEG] - Statistics: 4084280 bytes read, 4 seeks
Mar 04, 2019 02:02:22.199 [8480] DEBUG - Streams didn't change in analysis, not saving.
Mar 04, 2019 02:02:22.199 [8480] DEBUG - Updating media item 177, size=489836685, metadata_item_id=177
Mar 04, 2019 02:02:22.199 [8480] DEBUG - Updating part with ID=28611 [U:\Movies2\Storefront Hitchcock (1997).m4v]
Mar 04, 2019 02:02:22.201 [10940] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=analyzing
Mar 04, 2019 02:02:22.203 [10940] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=analyzing
Mar 04, 2019 02:02:22.203 [10940] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=thumbnailing
Mar 04, 2019 02:02:22.204 [10940] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=thumbnailing
Mar 04, 2019 02:02:22.204 [10940] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=idle
Mar 04, 2019 02:02:22.215 [10940] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=idle
