Aug 11, 2018 02:21:38.455 [2280] INFO - Plex Media Scanner v1.13.5.5291-6fa5e50a8 - Microsoft PC x64 - build: windows-i386 - GMT -07:00
Aug 11, 2018 02:21:38.455 [2280] INFO - Windows version: 6.2 (Build 9200), language en-US
Aug 11, 2018 02:21:38.456 [2280] INFO - 8 4013 MHz processor(s): Architecture=0, Level=21, Revision=512 Processor Identifier=AMD64 Family 21 Model 2 Stepping 0, AuthenticAMD
Aug 11, 2018 02:21:38.456 [2280] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Scanner.exe" --analyze --item 25,1412,219,330,1428,1434,258,141,110,1416 --log-file-suffix " Analysis" 
Aug 11, 2018 02:21:38.456 [12160] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Aug 11, 2018 02:21:38.634 [12160] DEBUG - Analyzing media parts for item 25 (Attack on Titan II: End of the World): 25
Aug 11, 2018 02:21:38.636 [12160] DEBUG - [ID 25] Media part analysis: F:\Videos\Movies\Attack on Titan Part 2\Attack on Titan 2 Live-Action.mkv
Aug 11, 2018 02:21:38.637 [12160] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Attack on Titan Part 2\Attack on Titan 2 Live-Action.mkv"
Aug 11, 2018 02:21:38.637 [12160] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Attack on Titan Part 2\Attack on Titan 2 Live-Action.mkv' for reading
Aug 11, 2018 02:21:38.637 [12160] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:21:38.663 [12160] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:21:38.668 [12160] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:38.668 [12160] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:38.668 [12160] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6406 bytes read:33585 seeks:2 nb_streams:2
Aug 11, 2018 02:21:38.669 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:38.669 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:38.671 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:38.671 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:38.671 [12160] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:21:38.672 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:38.672 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:38.672 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:38.672 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:38.672 [12160] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:21:38.675 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:38.677 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.677 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.677 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.677 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.680 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:38.682 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.683 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.683 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.683 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.688 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.689 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.689 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.689 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.697 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.697 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.697 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.697 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.704 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.704 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.704 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.704 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.709 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.709 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.710 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.710 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.715 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.715 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.715 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.715 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.717 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.717 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.717 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.718 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.721 [12160] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:21:38.721 [12160] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 57016 bytes read:99121 seeks:2 frames:58
Aug 11, 2018 02:21:38.722 [12160] DEBUG - [MI]  * ed52ec58bd5bcb52410172df5006a2f3fc514edc [mkv h264/dca] Duration: 5239276ms, 1920x804 (2.388060) 23.976fps, bitrate=3606kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Attack on Titan Part 2\Attack on Titan 2 Live-Action.mkv")
Aug 11, 2018 02:21:38.722 [12160] DEBUG - [MI] Importing 12 media chapters.
Aug 11, 2018 02:21:38.723 [12160] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:21:38.724 [12160] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Attack on Titan Part 2\Attack on Titan 2 Live-Action.mkv"
Aug 11, 2018 02:21:38.729 [12160] DEBUG - [FFMPEG] - Statistics: 99121 bytes read, 2 seeks
Aug 11, 2018 02:21:38.730 [12160] DEBUG - Updating media item 25, size=2362015551, metadata_item_id=25
Aug 11, 2018 02:21:38.731 [12160] DEBUG - Updating part with ID=25 [F:\Videos\Movies\Attack on Titan Part 2\Attack on Titan 2 Live-Action.mkv]
Aug 11, 2018 02:21:38.738 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=25&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:38.740 [12160] DEBUG - Analyzing media parts for item 1412 (Geass vs. Geass): 2082
Aug 11, 2018 02:21:38.743 [12160] DEBUG - [ID 2082] Media part analysis: F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 14 [1080p] [Dual] [khatake2].mkv
Aug 11, 2018 02:21:38.744 [12160] DEBUG - [MI] Opening input file: "F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 14 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:21:38.744 [12160] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 14 [1080p] [Dual] [khatake2].mkv' for reading
Aug 11, 2018 02:21:38.744 [12160] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:21:38.751 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=25&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:38.752 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=25&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:38.755 [12160] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:21:38.767 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=25&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:38.768 [12160] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:38.768 [12160] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:38.768 [12160] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:38.768 [12160] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:38.769 [12160] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:38.770 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=25&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:38.774 [12160] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1286827 bytes read:1321218 seeks:2 nb_streams:20
Aug 11, 2018 02:21:38.775 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:38.775 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:38.789 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=25&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:38.809 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:38.809 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:38.810 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:38.810 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:38.810 [12160] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:21:38.810 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:38.810 [12160] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:21:38.814 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:38.823 [12160] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Aug 11, 2018 02:21:38.823 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.826 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:38.831 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.843 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:38.858 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.872 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.883 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.898 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.907 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:38.917 [12160] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:21:38.918 [12160] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2758159 bytes read:2796497 seeks:2 frames:58
Aug 11, 2018 02:21:38.919 [12160] DEBUG - [MI]  * 32b61cd9e5528ecf888699a45a576dc755e20f21 [mkv h264/aac] Duration: 1449448ms, 1920x1080 (1.777778) 23.976fps, bitrate=2025kbps, audio channels=2, optimizedForStreaming=0 ("F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 14 [1080p] [Dual] [khatake2].mkv")
Aug 11, 2018 02:21:38.920 [12160] DEBUG - [MI] Importing 6 media chapters.
Aug 11, 2018 02:21:38.921 [12160] DEBUG - [MI] Closing input file: "F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 14 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:21:38.932 [12160] DEBUG - [FFMPEG] - Statistics: 2796497 bytes read, 2 seeks
Aug 11, 2018 02:21:38.934 [12160] DEBUG - Updating media item 2082, size=367061196, metadata_item_id=1412
Aug 11, 2018 02:21:38.934 [12160] DEBUG - Updating part with ID=2082 [F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 14 [1080p] [Dual] [khatake2].mkv]
Aug 11, 2018 02:21:38.941 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1412&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:38.951 [12160] DEBUG - Analyzing media parts for item 219 (Paranormal Activity): 219
Aug 11, 2018 02:21:38.953 [12160] DEBUG - [ID 219] Media part analysis: F:\Videos\Movies\Paranormal Activity\Paranormal Activity.mp4
Aug 11, 2018 02:21:38.953 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1412&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:38.954 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1412&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:38.955 [12160] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Paranormal Activity\Paranormal Activity.mp4"
Aug 11, 2018 02:21:38.955 [12160] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Paranormal Activity\Paranormal Activity.mp4' for reading
Aug 11, 2018 02:21:38.955 [12160] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:21:38.973 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1412&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:38.974 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1412&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:21:38.985 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1412&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.002 [12160] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 11, 2018 02:21:39.003 [12160] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Aug 11, 2018 02:21:39.003 [12160] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:21:39.033 [12160] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Aug 11, 2018 02:21:39.033 [12160] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:21:39.048 [12160] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:21:39.060 [12160] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2283764591 bytes read:3221236 seeks:1 nb_streams:3
Aug 11, 2018 02:21:39.061 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.061 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.064 [12160] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:21:39.065 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.065 [12160] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:21:39.069 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:39.072 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.075 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:39.079 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.091 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.104 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.115 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.124 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.133 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.137 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.143 [12160] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:21:39.143 [12160] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3203565 bytes read:3254004 seeks:2 frames:37
Aug 11, 2018 02:21:39.144 [12160] DEBUG - [MI] Opening "F:\Videos\Movies\Paranormal Activity\Paranormal Activity.mp4" for MP4 atom parsing
Aug 11, 2018 02:21:39.144 [12160] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 20 at offset 0
Aug 11, 2018 02:21:39.144 [12160] DEBUG - Mp4AtomParser: Found moov atom of size 3200769 at offset 20
Aug 11, 2018 02:21:39.149 [12160] DEBUG - Mp4AtomParser: Found trak atom of size 1453397 at offset 157
Aug 11, 2018 02:21:39.149 [12160] DEBUG - Mp4AtomParser: Found trak atom of size 1149951 at offset 1453554
Aug 11, 2018 02:21:39.149 [12160] DEBUG - Mp4AtomParser: Found trak atom of size 597284 at offset 2603505
Aug 11, 2018 02:21:39.149 [12160] DEBUG - Mp4AtomParser: Found mdia atom of size 1453297 at offset 257
Aug 11, 2018 02:21:39.150 [12160] DEBUG - Mp4AtomParser: Found minf atom of size 1453211 at offset 343
Aug 11, 2018 02:21:39.150 [12160] DEBUG - Mp4AtomParser: Found stbl atom of size 1453147 at offset 407
Aug 11, 2018 02:21:39.150 [12160] DEBUG - Mp4AtomParser: Found mdia atom of size 1149851 at offset 1453654
Aug 11, 2018 02:21:39.150 [12160] DEBUG - Mp4AtomParser: Found minf atom of size 1149766 at offset 1453739
Aug 11, 2018 02:21:39.150 [12160] DEBUG - Mp4AtomParser: Found stbl atom of size 1149706 at offset 1453799
Aug 11, 2018 02:21:39.150 [12160] DEBUG - Mp4AtomParser: Found mdia atom of size 597184 at offset 2603605
Aug 11, 2018 02:21:39.150 [12160] DEBUG - Mp4AtomParser: Found minf atom of size 597092 at offset 2603697
Aug 11, 2018 02:21:39.150 [12160] DEBUG - Mp4AtomParser: Found stbl atom of size 597032 at offset 2603757
Aug 11, 2018 02:21:39.151 [12160] DEBUG - [MI]  * aa40ea12f9e9f84f49f04bc65541ce7e23720cb4 [mp4 h264/aac] Duration: 5177300ms, 1920x1080 (1.777778) 23.976fps, bitrate=3528kbps, audio channels=6, optimizedForStreaming=1 ("F:\Videos\Movies\Paranormal Activity\Paranormal Activity.mp4")
Aug 11, 2018 02:21:39.152 [12160] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:21:39.152 [12160] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Paranormal Activity\Paranormal Activity.mp4"
Aug 11, 2018 02:21:39.159 [12160] DEBUG - [FFMPEG] - Statistics: 3254004 bytes read, 2 seeks
Aug 11, 2018 02:21:39.159 [12160] DEBUG - Committing media stream changes for part 219
Aug 11, 2018 02:21:39.164 [12160] DEBUG - Updating media item 219, size=2283764626, metadata_item_id=219
Aug 11, 2018 02:21:39.164 [12160] DEBUG - Updating part with ID=219 [F:\Videos\Movies\Paranormal Activity\Paranormal Activity.mp4]
Aug 11, 2018 02:21:39.172 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=219&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:39.186 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=219&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:39.186 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=219&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:39.191 [12160] DEBUG - Analyzing media parts for item 330 (Grimsby): 330
Aug 11, 2018 02:21:39.192 [12160] DEBUG - [ID 330] Media part analysis: F:\Videos\Movies\The Brothers Grimsby\The Brothers Grimsby.mkv
Aug 11, 2018 02:21:39.194 [12160] DEBUG - [MI] Opening input file: "F:\Videos\Movies\The Brothers Grimsby\The Brothers Grimsby.mkv"
Aug 11, 2018 02:21:39.194 [12160] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\The Brothers Grimsby\The Brothers Grimsby.mkv' for reading
Aug 11, 2018 02:21:39.194 [12160] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:21:39.199 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=219&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:39.200 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=219&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.213 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=219&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.243 [12160] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:21:39.253 [12160] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.253 [12160] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.254 [12160] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6605 bytes read:33585 seeks:2 nb_streams:2
Aug 11, 2018 02:21:39.255 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.255 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.256 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.256 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.257 [12160] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:21:39.257 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.257 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.257 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.257 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.257 [12160] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:21:39.260 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:39.266 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.266 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.266 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.266 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.269 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:39.272 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.272 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.272 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.272 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.279 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.279 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.279 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.279 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.286 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.286 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.286 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.286 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.294 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.294 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.294 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.294 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.299 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.299 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.299 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.299 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.304 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.304 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.304 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.304 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.309 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.309 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.309 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.309 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.315 [12160] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:21:39.315 [12160] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 62631 bytes read:99121 seeks:2 frames:58
Aug 11, 2018 02:21:39.316 [12160] DEBUG - [MI]  * 1e710987577e32cc8a4460c73f705a0432a95c88 [mkv h264/dca] Duration: 4991979ms, 1920x804 (2.388060) 23.976fps, bitrate=3604kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\The Brothers Grimsby\The Brothers Grimsby.mkv")
Aug 11, 2018 02:21:39.316 [12160] DEBUG - [MI] Importing 16 media chapters.
Aug 11, 2018 02:21:39.316 [12160] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:21:39.319 [12160] DEBUG - [MI] Closing input file: "F:\Videos\Movies\The Brothers Grimsby\The Brothers Grimsby.mkv"
Aug 11, 2018 02:21:39.322 [12160] DEBUG - [FFMPEG] - Statistics: 99121 bytes read, 2 seeks
Aug 11, 2018 02:21:39.324 [12160] DEBUG - Updating media item 330, size=2249467849, metadata_item_id=330
Aug 11, 2018 02:21:39.325 [12160] DEBUG - Updating part with ID=330 [F:\Videos\Movies\The Brothers Grimsby\The Brothers Grimsby.mkv]
Aug 11, 2018 02:21:39.332 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=330&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:39.337 [12160] DEBUG - Analyzing media parts for item 1428 (Counterattack at the Gallows): 2097
Aug 11, 2018 02:21:39.340 [12160] DEBUG - [ID 2097] Media part analysis: F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 04 [1080p] [Dual] [khatake2].mkv
Aug 11, 2018 02:21:39.341 [12160] DEBUG - [MI] Opening input file: "F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 04 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:21:39.341 [12160] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 04 [1080p] [Dual] [khatake2].mkv' for reading
Aug 11, 2018 02:21:39.341 [12160] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:21:39.344 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=330&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:39.345 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=330&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:39.358 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=330&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:39.359 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=330&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.361 [12160] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:21:39.378 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=330&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.457 [12160] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.457 [12160] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.457 [12160] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.457 [12160] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.458 [12160] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.462 [12160] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1491155 bytes read:1509961 seeks:2 nb_streams:25
Aug 11, 2018 02:21:39.462 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.462 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.483 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.483 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.484 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.484 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.484 [12160] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:21:39.484 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.484 [12160] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:21:39.488 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:39.494 [12160] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Aug 11, 2018 02:21:39.494 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.497 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:39.501 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.512 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.523 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.534 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.543 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.551 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.556 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.564 [12160] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:21:39.565 [12160] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2578238 bytes read:2624073 seeks:2 frames:58
Aug 11, 2018 02:21:39.566 [12160] DEBUG - [MI]  * e4f9303aa367f9435528fd6664a3edcb0ad70f11 [mkv h264/aac] Duration: 1448906ms, 1920x1080 (1.777778) 23.976fps, bitrate=2028kbps, audio channels=2, optimizedForStreaming=0 ("F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 04 [1080p] [Dual] [khatake2].mkv")
Aug 11, 2018 02:21:39.567 [12160] DEBUG - [MI] Importing 6 media chapters.
Aug 11, 2018 02:21:39.568 [12160] DEBUG - [MI] Closing input file: "F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 04 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:21:39.575 [12160] DEBUG - [FFMPEG] - Statistics: 2624073 bytes read, 2 seeks
Aug 11, 2018 02:21:39.576 [12160] DEBUG - Updating media item 2097, size=367324214, metadata_item_id=1428
Aug 11, 2018 02:21:39.577 [12160] DEBUG - Updating part with ID=2097 [F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 04 [1080p] [Dual] [khatake2].mkv]
Aug 11, 2018 02:21:39.583 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1428&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:39.586 [12160] DEBUG - Analyzing media parts for item 1434 (When Shen Hu Wins Glory): 2103
Aug 11, 2018 02:21:39.588 [12160] DEBUG - [ID 2103] Media part analysis: F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 10 [1080p] [Dual] [khatake2].mkv
Aug 11, 2018 02:21:39.590 [12160] DEBUG - [MI] Opening input file: "F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 10 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:21:39.590 [12160] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 10 [1080p] [Dual] [khatake2].mkv' for reading
Aug 11, 2018 02:21:39.590 [12160] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:21:39.596 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1428&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:39.597 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1428&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:39.600 [12160] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:21:39.612 [12160] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.613 [12160] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.613 [12160] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.613 [12160] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.613 [12160] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.615 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1428&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:39.615 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1428&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.618 [12160] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1491155 bytes read:1509961 seeks:2 nb_streams:25
Aug 11, 2018 02:21:39.618 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.619 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.628 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1428&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.644 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.645 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.646 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.646 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.646 [12160] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:21:39.646 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.646 [12160] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:21:39.650 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:39.658 [12160] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Aug 11, 2018 02:21:39.658 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.661 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:39.668 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.679 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.690 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.703 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.714 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.724 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.733 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.743 [12160] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:21:39.745 [12160] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2256698 bytes read:2296393 seeks:2 frames:58
Aug 11, 2018 02:21:39.745 [12160] DEBUG - [MI]  * 574dd539fbb870efc7b40e9160a393a107060974 [mkv h264/aac] Duration: 1448406ms, 1920x1080 (1.777778) 23.976fps, bitrate=2029kbps, audio channels=2, optimizedForStreaming=0 ("F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 10 [1080p] [Dual] [khatake2].mkv")
Aug 11, 2018 02:21:39.746 [12160] DEBUG - [MI] Importing 6 media chapters.
Aug 11, 2018 02:21:39.747 [12160] DEBUG - [MI] Closing input file: "F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 10 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:21:39.753 [12160] DEBUG - [FFMPEG] - Statistics: 2296393 bytes read, 2 seeks
Aug 11, 2018 02:21:39.755 [12160] DEBUG - Updating media item 2103, size=367362393, metadata_item_id=1434
Aug 11, 2018 02:21:39.755 [12160] DEBUG - Updating part with ID=2103 [F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 10 [1080p] [Dual] [khatake2].mkv]
Aug 11, 2018 02:21:39.761 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1434&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:39.775 [12160] DEBUG - Analyzing media parts for item 258 (Rush Hour 2): 258
Aug 11, 2018 02:21:39.775 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1434&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:39.775 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1434&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:39.776 [12160] DEBUG - [ID 258] Media part analysis: F:\Videos\Movies\Rush Hour 2\Rush Hour 2.mp4
Aug 11, 2018 02:21:39.778 [12160] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Rush Hour 2\Rush Hour 2.mp4"
Aug 11, 2018 02:21:39.778 [12160] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Rush Hour 2\Rush Hour 2.mp4' for reading
Aug 11, 2018 02:21:39.778 [12160] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:21:39.788 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1434&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:39.789 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1434&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.794 [12160] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 11, 2018 02:21:39.794 [12160] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Aug 11, 2018 02:21:39.802 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1434&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.807 [12160] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:21:39.837 [12160] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Aug 11, 2018 02:21:39.838 [12160] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:21:39.845 [12160] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:21:39.845 [12160] WARN - [FFMPEG] - stream 0, timescale not set
Aug 11, 2018 02:21:39.846 [12160] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 3813009386 bytes read:1771140 seeks:4 nb_streams:4
Aug 11, 2018 02:21:39.846 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.846 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.848 [12160] DEBUG - [FFMPEG] - marker=d8 avail_size_in_buf=16382
Aug 11, 2018 02:21:39.848 [12160] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:21:39.848 [12160] DEBUG - [FFMPEG] - marker=e0 avail_size_in_buf=16380
Aug 11, 2018 02:21:39.848 [12160] DEBUG - [FFMPEG] - marker parser used 16 bytes (128 bits)
Aug 11, 2018 02:21:39.848 [12160] DEBUG - [FFMPEG] - marker=db avail_size_in_buf=16362
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - index=0
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - qscale[0]: 3
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - marker parser used 67 bytes (536 bits)
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - marker=db avail_size_in_buf=16293
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - index=1
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - qscale[1]: 6
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - marker parser used 67 bytes (536 bits)
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - marker=c0 avail_size_in_buf=16224
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - Changing bps from 0 to 8
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - sof0: picture: 151x227
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - component 0 2:2 id: 0 quant:0
Aug 11, 2018 02:21:39.849 [12160] DEBUG - [FFMPEG] - component 1 1:1 id: 1 quant:1
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - component 2 1:1 id: 2 quant:1
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - pix fmt id 22111100
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - Format yuvj420p chosen by get_format().
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - marker parser used 17 bytes (136 bits)
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=16205
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=16172
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=15989
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=15956
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:21:39.850 [12160] DEBUG - [FFMPEG] - escaping removed 7741 bytes
Aug 11, 2018 02:21:39.851 [12160] DEBUG - [FFMPEG] - marker=da avail_size_in_buf=15773
Aug 11, 2018 02:21:39.851 [12160] DEBUG - [FFMPEG] - marker parser used 8032 bytes (64256 bits)
Aug 11, 2018 02:21:39.851 [12160] DEBUG - [FFMPEG] - marker=d9 avail_size_in_buf=7702
Aug 11, 2018 02:21:39.851 [12160] DEBUG - [FFMPEG] - decode frame unused 7702 bytes
Aug 11, 2018 02:21:39.851 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.851 [12160] ERROR - [FFMPEG] - no frame!
Aug 11, 2018 02:21:39.851 [12160] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:21:39.852 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.852 [12160] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:21:39.855 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:39.857 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.859 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:39.861 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.867 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.874 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.879 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.883 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.886 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:39.890 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:39.894 [12160] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:21:39.894 [12160] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 1657739 bytes read:1803908 seeks:5 frames:14
Aug 11, 2018 02:21:39.894 [12160] DEBUG - [MI] Opening "F:\Videos\Movies\Rush Hour 2\Rush Hour 2.mp4" for MP4 atom parsing
Aug 11, 2018 02:21:39.895 [12160] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Aug 11, 2018 02:21:39.895 [12160] DEBUG - Mp4AtomParser: Skipping free atom of size 1653488 at offset 28
Aug 11, 2018 02:21:39.895 [12160] DEBUG - Mp4AtomParser: Skipping mdat atom of size 3809683034 at offset 1653516
Aug 11, 2018 02:21:39.895 [12160] DEBUG - Mp4AtomParser: Skipping mdat atom of size 506 at offset 3811336550
Aug 11, 2018 02:21:39.895 [12160] DEBUG - Mp4AtomParser: Skipping mdat atom of size 8 at offset 3811337056
Aug 11, 2018 02:21:39.895 [12160] DEBUG - Mp4AtomParser: Skipping mdat atom of size 8 at offset 3811337064
Aug 11, 2018 02:21:39.895 [12160] DEBUG - Mp4AtomParser: Skipping mdat atom of size 8 at offset 3811337072
Aug 11, 2018 02:21:39.895 [12160] DEBUG - Mp4AtomParser: Found moov atom of size 1672306 at offset 3811337080
Aug 11, 2018 02:21:39.898 [12160] DEBUG - Mp4AtomParser: Found trak atom of size 1510412 at offset 3811337220
Aug 11, 2018 02:21:39.899 [12160] DEBUG - Mp4AtomParser: Found trak atom of size 142824 at offset 3812847632
Aug 11, 2018 02:21:39.899 [12160] DEBUG - Mp4AtomParser: Found trak atom of size 712 at offset 3812990456
Aug 11, 2018 02:21:39.899 [12160] DEBUG - Mp4AtomParser: Found mdia atom of size 1510292 at offset 3811337320
Aug 11, 2018 02:21:39.899 [12160] DEBUG - Mp4AtomParser: Found minf atom of size 1510219 at offset 3811337393
Aug 11, 2018 02:21:39.899 [12160] DEBUG - Mp4AtomParser: Found stbl atom of size 1510155 at offset 3811337457
Aug 11, 2018 02:21:39.899 [12160] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 3812847612
Aug 11, 2018 02:21:39.899 [12160] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 3812847620
Aug 11, 2018 02:21:39.899 [12160] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Aug 11, 2018 02:21:39.899 [12160] DEBUG - Mp4AtomParser: Found mdia atom of size 142662 at offset 3812847732
Aug 11, 2018 02:21:39.900 [12160] DEBUG - Mp4AtomParser: Found minf atom of size 142575 at offset 3812847819
Aug 11, 2018 02:21:39.900 [12160] DEBUG - Mp4AtomParser: Found stbl atom of size 142515 at offset 3812847879
Aug 11, 2018 02:21:39.900 [12160] DEBUG - Mp4AtomParser: Found mdia atom of size 612 at offset 3812990556
Aug 11, 2018 02:21:39.900 [12160] DEBUG - Mp4AtomParser: Found minf atom of size 539 at offset 3812990629
Aug 11, 2018 02:21:39.900 [12160] DEBUG - Mp4AtomParser: Found stbl atom of size 419 at offset 3812990749
Aug 11, 2018 02:21:39.900 [12160] DEBUG - [MI]  * 68897becc51b689150d8aff14022a477dc5b2002 [mp4 h264/ac3] Duration: 5408032ms, 1920x800 (2.400000) 23.976fps, bitrate=5640kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Rush Hour 2\Rush Hour 2.mp4")
Aug 11, 2018 02:21:39.901 [12160] DEBUG - [MI] Importing 16 media chapters.
Aug 11, 2018 02:21:39.903 [12160] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Rush Hour 2\Rush Hour 2.mp4"
Aug 11, 2018 02:21:39.907 [12160] DEBUG - [FFMPEG] - Statistics: 1803908 bytes read, 5 seeks
Aug 11, 2018 02:21:39.908 [12160] DEBUG - Updating media item 258, size=3813009386, metadata_item_id=258
Aug 11, 2018 02:21:39.909 [12160] DEBUG - Updating part with ID=258 [F:\Videos\Movies\Rush Hour 2\Rush Hour 2.mp4]
Aug 11, 2018 02:21:39.915 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=258&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:39.923 [12160] DEBUG - It took 0.000000 ms to retrieve 143 items.
Aug 11, 2018 02:21:39.927 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=258&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:39.927 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=258&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:39.940 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=258&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:39.941 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=258&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.947 [12160] DEBUG - Analyzing media parts for item 141 (Independence Day: Resurgence): 141
Aug 11, 2018 02:21:39.948 [12160] DEBUG - [ID 141] Media part analysis: F:\Videos\Movies\Independence Day Resurgence\Independence Day Resurgence.mkv
Aug 11, 2018 02:21:39.950 [12160] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Independence Day Resurgence\Independence Day Resurgence.mkv"
Aug 11, 2018 02:21:39.950 [12160] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Independence Day Resurgence\Independence Day Resurgence.mkv' for reading
Aug 11, 2018 02:21:39.950 [12160] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:21:39.953 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=258&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:39.988 [12160] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:21:39.995 [12160] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.995 [12160] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.995 [12160] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:39.996 [12160] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7078 bytes read:33979 seeks:2 nb_streams:3
Aug 11, 2018 02:21:39.996 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.996 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.998 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:39.998 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:39.998 [12160] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:21:39.998 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.998 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.998 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.999 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:39.999 [12160] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:21:40.001 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:40.004 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.004 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.004 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.004 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.006 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:40.010 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.010 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.010 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.010 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.016 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.016 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.016 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.016 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.023 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.023 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.023 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.023 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.030 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.030 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.030 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.030 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.035 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.035 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.035 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.035 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.040 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.040 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.040 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.040 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.042 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.042 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.043 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.043 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.046 [12160] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:21:40.047 [12160] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 57686 bytes read:99515 seeks:2 frames:58
Aug 11, 2018 02:21:40.047 [12160] DEBUG - [MI]  * 201062b3a1d387f3fbd6446fd4108a382ba4b000 [mkv h264/dca] Duration: 7184177ms, 1920x800 (2.400000) 23.976fps, bitrate=3606kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Independence Day Resurgence\Independence Day Resurgence.mkv")
Aug 11, 2018 02:21:40.048 [12160] DEBUG - [MI] Importing 25 media chapters.
Aug 11, 2018 02:21:40.050 [12160] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Independence Day Resurgence\Independence Day Resurgence.mkv"
Aug 11, 2018 02:21:40.053 [12160] DEBUG - [FFMPEG] - Statistics: 99515 bytes read, 2 seeks
Aug 11, 2018 02:21:40.055 [12160] DEBUG - Updating media item 141, size=3238570406, metadata_item_id=141
Aug 11, 2018 02:21:40.055 [12160] DEBUG - Updating part with ID=141 [F:\Videos\Movies\Independence Day Resurgence\Independence Day Resurgence.mkv]
Aug 11, 2018 02:21:40.062 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=141&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:40.069 [12160] DEBUG - It took 31.250000 ms to retrieve 139 items.
Aug 11, 2018 02:21:40.073 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=141&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:40.073 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=141&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:40.086 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=141&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:40.087 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=141&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:40.096 [12160] DEBUG - Analyzing media parts for item 110 (Guardians of the Galaxy): 110
Aug 11, 2018 02:21:40.098 [12160] DEBUG - [ID 110] Media part analysis: F:\Videos\Movies\Guardians of the Galaxy\Guardians Of The Galaxy.mkv
Aug 11, 2018 02:21:40.100 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=141&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:40.099 [12160] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Guardians of the Galaxy\Guardians Of The Galaxy.mkv"
Aug 11, 2018 02:21:40.100 [12160] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Guardians of the Galaxy\Guardians Of The Galaxy.mkv' for reading
Aug 11, 2018 02:21:40.100 [12160] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:21:40.146 [12160] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:21:40.150 [12160] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:40.150 [12160] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:40.151 [12160] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6850 bytes read:33659 seeks:2 nb_streams:2
Aug 11, 2018 02:21:40.151 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:40.152 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:40.153 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:40.153 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:40.153 [12160] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:21:40.153 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:40.154 [12160] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:21:40.157 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:40.160 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.163 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:40.168 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.177 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.188 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.199 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.206 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.215 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.218 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.224 [12160] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:21:40.224 [12160] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 57108 bytes read:99195 seeks:2 frames:58
Aug 11, 2018 02:21:40.225 [12160] DEBUG - [MI]  * 81685471e7654927f146337de8c091fcf6249613 [mkv h264/dca] Duration: 7251200ms, 1920x1080 (1.777778) 23.976fps, bitrate=4505kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Guardians of the Galaxy\Guardians Of The Galaxy.mkv")
Aug 11, 2018 02:21:40.225 [12160] DEBUG - [MI] Importing 21 media chapters.
Aug 11, 2018 02:21:40.226 [12160] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:21:40.227 [12160] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:21:40.228 [12160] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:21:40.240 [12160] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:21:40.241 [12160] DEBUG - Updating metadata item (save) (Guardians of the Galaxy) with ID 110
Aug 11, 2018 02:21:40.249 [12160] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Guardians of the Galaxy\Guardians Of The Galaxy.mkv"
Aug 11, 2018 02:21:40.253 [12160] DEBUG - [FFMPEG] - Statistics: 99195 bytes read, 2 seeks
Aug 11, 2018 02:21:40.254 [12160] DEBUG - Updating media item 110, size=4083374417, metadata_item_id=110
Aug 11, 2018 02:21:40.255 [12160] DEBUG - Updating part with ID=110 [F:\Videos\Movies\Guardians of the Galaxy\Guardians Of The Galaxy.mkv]
Aug 11, 2018 02:21:40.261 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:40.264 [12160] DEBUG - Analyzing media parts for item 1416 (I Order You, Suzaku Kururugi): 2086
Aug 11, 2018 02:21:40.267 [12160] DEBUG - [ID 2086] Media part analysis: F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 18 [1080p] [Dual] [khatake2].mkv
Aug 11, 2018 02:21:40.268 [12160] DEBUG - [MI] Opening input file: "F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 18 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:21:40.268 [12160] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 18 [1080p] [Dual] [khatake2].mkv' for reading
Aug 11, 2018 02:21:40.268 [12160] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:21:40.274 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:40.274 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:40.280 [12160] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:21:40.288 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:40.289 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:40.294 [12160] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:40.294 [12160] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:40.294 [12160] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:40.294 [12160] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:40.294 [12160] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:21:40.298 [12160] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1286658 bytes read:1321077 seeks:2 nb_streams:20
Aug 11, 2018 02:21:40.299 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:40.299 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:40.302 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=110&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:21:40.325 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:40.325 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:40.326 [12160] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:21:40.326 [12160] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:21:40.326 [12160] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:21:40.326 [12160] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:21:40.326 [12160] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:21:40.330 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:40.337 [12160] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Aug 11, 2018 02:21:40.338 [12160] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Aug 11, 2018 02:21:40.338 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.341 [12160] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:21:40.346 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.357 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.368 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:21:40.380 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.387 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.393 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.404 [12160] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:21:40.414 [12160] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:21:40.416 [12160] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2085489 bytes read:2140277 seeks:2 frames:58
Aug 11, 2018 02:21:40.416 [12160] DEBUG - [MI]  * 84c1d838d920aff58ece0555bc9571486b46b206 [mkv h264/aac] Duration: 1449448ms, 1920x1080 (1.777778) 23.976fps, bitrate=2026kbps, audio channels=2, optimizedForStreaming=0 ("F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 18 [1080p] [Dual] [khatake2].mkv")
Aug 11, 2018 02:21:40.417 [12160] DEBUG - [MI] Importing 6 media chapters.
Aug 11, 2018 02:21:40.418 [12160] DEBUG - [MI] Closing input file: "F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 18 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:21:40.425 [12160] DEBUG - [FFMPEG] - Statistics: 2140277 bytes read, 2 seeks
Aug 11, 2018 02:21:40.427 [12160] DEBUG - Updating media item 2086, size=367153289, metadata_item_id=1416
Aug 11, 2018 02:21:40.428 [12160] DEBUG - Updating part with ID=2086 [F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 18 [1080p] [Dual] [khatake2].mkv]
Aug 11, 2018 02:21:40.434 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1416&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:40.447 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1416&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:21:40.447 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1416&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:40.459 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1416&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:21:40.459 [4428] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1416&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:21:40.471 [4428] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1416&metadataType=4&state=-1&mediaState=idle
