Aug 11, 2018 02:20:49.130 [4400] INFO - Plex Media Scanner v1.13.5.5291-6fa5e50a8 - Microsoft PC x64 - build: windows-i386 - GMT -07:00
Aug 11, 2018 02:20:49.131 [4400] INFO - Windows version: 6.2 (Build 9200), language en-US
Aug 11, 2018 02:20:49.131 [4400] 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:20:49.132 [4400] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Scanner.exe" --analyze --item 6036,1411,41,1427,260,11768,92,1423,342,302 --log-file-suffix " Analysis" 
Aug 11, 2018 02:20:49.134 [12036] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Aug 11, 2018 02:20:49.296 [12036] DEBUG - Analyzing media parts for item 6036 (Dragon Ball Z: Battle of Gods): 6499
Aug 11, 2018 02:20:49.297 [12036] DEBUG - [ID 6499] Media part analysis: F:\Videos\Anime Movies\Dragon Ball Z Battle of Gods\Dragon Ball Z Movie 14 Battle of Gods.mp4
Aug 11, 2018 02:20:49.299 [12036] DEBUG - [MI] Opening input file: "F:\Videos\Anime Movies\Dragon Ball Z Battle of Gods\Dragon Ball Z Movie 14 Battle of Gods.mp4"
Aug 11, 2018 02:20:49.299 [12036] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime Movies\Dragon Ball Z Battle of Gods\Dragon Ball Z Movie 14 Battle of Gods.mp4' for reading
Aug 11, 2018 02:20:49.299 [12036] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:20:49.315 [12036] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 11, 2018 02:20:49.315 [12036] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Aug 11, 2018 02:20:49.315 [12036] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:20:49.328 [12036] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 0, duration: 154831680
Aug 11, 2018 02:20:49.341 [12036] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:20:49.357 [12036] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1939797 bytes read:1948948 seeks:0 nb_streams:2
Aug 11, 2018 02:20:49.358 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:49.358 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:49.362 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:49.362 [12036] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:20:49.370 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.381 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.391 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.400 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.409 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.419 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.430 [12036] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:20:49.431 [12036] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2207521 bytes read:2211092 seeks:0 frames:13
Aug 11, 2018 02:20:49.431 [12036] DEBUG - [MI] Opening "F:\Videos\Anime Movies\Dragon Ball Z Battle of Gods\Dragon Ball Z Movie 14 Battle of Gods.mp4" for MP4 atom parsing
Aug 11, 2018 02:20:49.431 [12036] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 24 at offset 0
Aug 11, 2018 02:20:49.431 [12036] DEBUG - Mp4AtomParser: Found moov atom of size 1939765 at offset 24
Aug 11, 2018 02:20:49.434 [12036] DEBUG - Mp4AtomParser: Found trak atom of size 690038 at offset 161
Aug 11, 2018 02:20:49.434 [12036] DEBUG - Mp4AtomParser: Found trak atom of size 1249473 at offset 690199
Aug 11, 2018 02:20:49.434 [12036] DEBUG - Mp4AtomParser: Found mdia atom of size 689902 at offset 297
Aug 11, 2018 02:20:49.434 [12036] DEBUG - Mp4AtomParser: Found minf atom of size 689817 at offset 382
Aug 11, 2018 02:20:49.434 [12036] DEBUG - Mp4AtomParser: Found stbl atom of size 689753 at offset 446
Aug 11, 2018 02:20:49.434 [12036] DEBUG - Mp4AtomParser: Found mdia atom of size 1249373 at offset 690299
Aug 11, 2018 02:20:49.434 [12036] DEBUG - Mp4AtomParser: Found minf atom of size 1249257 at offset 690415
Aug 11, 2018 02:20:49.435 [12036] DEBUG - Mp4AtomParser: Found stbl atom of size 1249197 at offset 690475
Aug 11, 2018 02:20:49.435 [12036] DEBUG - [MI]  * f1c14ba28cff9e5fbd01bcd10459adb9c2abf6bc [mp4 h264/aac] Duration: 6451338ms, 1920x1080 (1.777778) 23.976fps, bitrate=2707kbps, audio channels=2, optimizedForStreaming=1 ("F:\Videos\Anime Movies\Dragon Ball Z Battle of Gods\Dragon Ball Z Movie 14 Battle of Gods.mp4")
Aug 11, 2018 02:20:49.436 [12036] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:20:49.436 [12036] DEBUG - [MI] Closing input file: "F:\Videos\Anime Movies\Dragon Ball Z Battle of Gods\Dragon Ball Z Movie 14 Battle of Gods.mp4"
Aug 11, 2018 02:20:49.440 [12036] DEBUG - [FFMPEG] - Statistics: 2211092 bytes read, 0 seeks
Aug 11, 2018 02:20:49.442 [12036] DEBUG - Updating media item 6499, size=2183052807, metadata_item_id=6036
Aug 11, 2018 02:20:49.443 [12036] DEBUG - Updating part with ID=6499 [F:\Videos\Anime Movies\Dragon Ball Z Battle of Gods\Dragon Ball Z Movie 14 Battle of Gods.mp4]
Aug 11, 2018 02:20:49.450 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=6036&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:49.453 [12036] DEBUG - Analyzing media parts for item 1411 (Shirley at Gunpoint): 2081
Aug 11, 2018 02:20:49.455 [12036] DEBUG - [ID 2081] Media part analysis: F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 13 [1080p] [Dual] [khatake2].mkv
Aug 11, 2018 02:20:49.456 [12036] DEBUG - [MI] Opening input file: "F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 13 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:20:49.456 [12036] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 13 [1080p] [Dual] [khatake2].mkv' for reading
Aug 11, 2018 02:20:49.457 [12036] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:20:49.464 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=6036&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:49.464 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=6036&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:49.469 [12036] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:20:49.477 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=6036&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:49.477 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=6036&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:49.480 [12036] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.480 [12036] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.480 [12036] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.480 [12036] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.481 [12036] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.485 [12036] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1286827 bytes read:1321218 seeks:2 nb_streams:20
Aug 11, 2018 02:20:49.486 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:49.486 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:49.492 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=6036&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:49.511 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:49.511 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:49.512 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:49.512 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:49.512 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.512 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:49.512 [12036] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:20:49.516 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:49.524 [12036] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Aug 11, 2018 02:20:49.524 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.527 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:49.531 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.542 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.552 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.564 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.572 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.580 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.593 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.603 [12036] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:20:49.605 [12036] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 1803848 bytes read:1845506 seeks:2 frames:58
Aug 11, 2018 02:20:49.606 [12036] DEBUG - [MI]  * a44eae5b73a30575902c81b605176fcf483a6ea0 [mkv h264/aac] Duration: 1449407ms, 1920x1080 (1.777778) 23.976fps, bitrate=2025kbps, audio channels=2, optimizedForStreaming=0 ("F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 13 [1080p] [Dual] [khatake2].mkv")
Aug 11, 2018 02:20:49.606 [12036] DEBUG - [MI] Importing 6 media chapters.
Aug 11, 2018 02:20:49.608 [12036] DEBUG - [MI] Closing input file: "F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 13 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:20:49.615 [12036] DEBUG - [FFMPEG] - Statistics: 1845506 bytes read, 2 seeks
Aug 11, 2018 02:20:49.617 [12036] DEBUG - Updating media item 2081, size=366982924, metadata_item_id=1411
Aug 11, 2018 02:20:49.618 [12036] DEBUG - Updating part with ID=2081 [F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 13 [1080p] [Dual] [khatake2].mkv]
Aug 11, 2018 02:20:49.624 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1411&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:49.633 [12036] DEBUG - It took 31.250000 ms to retrieve 195 items.
Aug 11, 2018 02:20:49.637 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1411&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:49.638 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1411&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:49.650 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1411&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:49.654 [12036] DEBUG - Analyzing media parts for item 41 (Batman v Superman: Dawn of Justice): 41
Aug 11, 2018 02:20:49.656 [12036] DEBUG - [ID 41] Media part analysis: F:\Videos\Movies\Batman Vs. Superman Dawn of Justice\Batman vs. Superman Dawn of Justice.mkv
Aug 11, 2018 02:20:49.657 [12036] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Batman Vs. Superman Dawn of Justice\Batman vs. Superman Dawn of Justice.mkv"
Aug 11, 2018 02:20:49.658 [12036] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Batman Vs. Superman Dawn of Justice\Batman vs. Superman Dawn of Justice.mkv' for reading
Aug 11, 2018 02:20:49.658 [12036] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:20:49.660 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1411&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:20:49.672 [12036] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:20:49.673 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1411&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:20:49.673 [12036] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.673 [12036] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.673 [12036] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.674 [12036] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 68790 bytes read:98304 seeks:0 nb_streams:7
Aug 11, 2018 02:20:49.675 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 1
Aug 11, 2018 02:20:49.675 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 1
Aug 11, 2018 02:20:49.679 [12036] DEBUG - [FFMPEG] - marker=d8 avail_size_in_buf=24550
Aug 11, 2018 02:20:49.679 [12036] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:20:49.679 [12036] DEBUG - [FFMPEG] - marker=e1 avail_size_in_buf=24548
Aug 11, 2018 02:20:49.679 [12036] DEBUG - [FFMPEG] - marker parser used 23 bytes (184 bits)
Aug 11, 2018 02:20:49.679 [12036] DEBUG - [FFMPEG] - marker=ec avail_size_in_buf=24522
Aug 11, 2018 02:20:49.679 [12036] DEBUG - [FFMPEG] - marker parser used 16 bytes (128 bits)
Aug 11, 2018 02:20:49.679 [12036] DEBUG - [FFMPEG] - marker=ed avail_size_in_buf=24503
Aug 11, 2018 02:20:49.680 [12036] DEBUG - [FFMPEG] - marker parser used 43 bytes (344 bits)
Aug 11, 2018 02:20:49.680 [12036] DEBUG - [FFMPEG] - marker=ee avail_size_in_buf=24457
Aug 11, 2018 02:20:49.680 [12036] DEBUG - [FFMPEG] - marker parser used 14 bytes (112 bits)
Aug 11, 2018 02:20:49.680 [12036] DEBUG - [FFMPEG] - marker=db avail_size_in_buf=24441
Aug 11, 2018 02:20:49.680 [12036] DEBUG - [FFMPEG] - index=0
Aug 11, 2018 02:20:49.680 [12036] DEBUG - [FFMPEG] - qscale[0]: 0
Aug 11, 2018 02:20:49.680 [12036] DEBUG - [FFMPEG] - index=1
Aug 11, 2018 02:20:49.680 [12036] DEBUG - [FFMPEG] - qscale[1]: 0
Aug 11, 2018 02:20:49.680 [12036] DEBUG - [FFMPEG] - marker parser used 132 bytes (1056 bits)
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - marker=c0 avail_size_in_buf=24307
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - Changing bps from 0 to 8
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - sof0: picture: 1067x600
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - component 0 1:1 id: 0 quant:0
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - component 1 1:1 id: 1 quant:1
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - component 2 1:1 id: 2 quant:1
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - pix fmt id 11111100
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - Format yuvj444p chosen by get_format().
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - marker parser used 17 bytes (136 bits)
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=24288
Aug 11, 2018 02:20:49.681 [12036] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - escaping removed 91 bytes
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - marker=da avail_size_in_buf=24087
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - marker parser used 23996 bytes (191968 bits)
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - marker=d9 avail_size_in_buf=0
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - decode frame unused 0 bytes
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - marker=d8 avail_size_in_buf=5035
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - marker=e1 avail_size_in_buf=5033
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - marker parser used 23 bytes (184 bits)
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - marker=ec avail_size_in_buf=5007
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - marker parser used 16 bytes (128 bits)
Aug 11, 2018 02:20:49.682 [12036] DEBUG - [FFMPEG] - marker=ee avail_size_in_buf=4988
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - marker parser used 14 bytes (112 bits)
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - marker=db avail_size_in_buf=4972
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - index=0
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - qscale[0]: 0
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - index=1
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - qscale[1]: 0
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - marker parser used 132 bytes (1056 bits)
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - marker=c0 avail_size_in_buf=4838
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - Changing bps from 0 to 8
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - sof0: picture: 120x176
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - component 0 1:1 id: 0 quant:0
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - component 1 1:1 id: 1 quant:1
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - component 2 1:1 id: 2 quant:1
Aug 11, 2018 02:20:49.683 [12036] DEBUG - [FFMPEG] - pix fmt id 11111100
Aug 11, 2018 02:20:49.684 [12036] DEBUG - [FFMPEG] - Format yuvj444p chosen by get_format().
Aug 11, 2018 02:20:49.684 [12036] DEBUG - [FFMPEG] - marker parser used 17 bytes (136 bits)
Aug 11, 2018 02:20:49.684 [12036] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=4819
Aug 11, 2018 02:20:49.684 [12036] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:20:49.684 [12036] DEBUG - [FFMPEG] - escaping removed 17 bytes
Aug 11, 2018 02:20:49.684 [12036] DEBUG - [FFMPEG] - marker=da avail_size_in_buf=4679
Aug 11, 2018 02:20:49.684 [12036] DEBUG - [FFMPEG] - marker parser used 4662 bytes (37296 bits)
Aug 11, 2018 02:20:49.684 [12036] DEBUG - [FFMPEG] - marker=d9 avail_size_in_buf=0
Aug 11, 2018 02:20:49.684 [12036] DEBUG - [FFMPEG] - decode frame unused 0 bytes
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker=d8 avail_size_in_buf=8560
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker=e1 avail_size_in_buf=8558
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker parser used 23 bytes (184 bits)
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker=ec avail_size_in_buf=8532
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker parser used 16 bytes (128 bits)
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker=ed avail_size_in_buf=8513
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker parser used 43 bytes (344 bits)
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker=ee avail_size_in_buf=8467
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker parser used 14 bytes (112 bits)
Aug 11, 2018 02:20:49.685 [12036] DEBUG - [FFMPEG] - marker=db avail_size_in_buf=8451
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - index=0
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - qscale[0]: 0
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - index=1
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - qscale[1]: 0
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - marker parser used 132 bytes (1056 bits)
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - marker=c0 avail_size_in_buf=8317
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - Changing bps from 0 to 8
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - sof0: picture: 213x120
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - component 0 1:1 id: 0 quant:0
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - component 1 1:1 id: 1 quant:1
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - component 2 1:1 id: 2 quant:1
Aug 11, 2018 02:20:49.686 [12036] DEBUG - [FFMPEG] - pix fmt id 11111100
Aug 11, 2018 02:20:49.687 [12036] DEBUG - [FFMPEG] - Format yuvj444p chosen by get_format().
Aug 11, 2018 02:20:49.687 [12036] DEBUG - [FFMPEG] - marker parser used 17 bytes (136 bits)
Aug 11, 2018 02:20:49.687 [12036] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=8298
Aug 11, 2018 02:20:49.687 [12036] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:20:49.687 [12036] DEBUG - [FFMPEG] - escaping removed 28 bytes
Aug 11, 2018 02:20:49.687 [12036] DEBUG - [FFMPEG] - marker=da avail_size_in_buf=8136
Aug 11, 2018 02:20:49.687 [12036] DEBUG - [FFMPEG] - marker parser used 8108 bytes (64864 bits)
Aug 11, 2018 02:20:49.687 [12036] DEBUG - [FFMPEG] - marker=d9 avail_size_in_buf=0
Aug 11, 2018 02:20:49.687 [12036] DEBUG - [FFMPEG] - decode frame unused 0 bytes
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - marker=d8 avail_size_in_buf=23752
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - marker=e1 avail_size_in_buf=23750
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - marker parser used 23 bytes (184 bits)
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - marker=ec avail_size_in_buf=23724
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - marker parser used 16 bytes (128 bits)
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - marker=ee avail_size_in_buf=23705
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - marker parser used 14 bytes (112 bits)
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - marker=db avail_size_in_buf=23689
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - index=0
Aug 11, 2018 02:20:49.688 [12036] DEBUG - [FFMPEG] - qscale[0]: 0
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - index=1
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - qscale[1]: 0
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - marker parser used 132 bytes (1056 bits)
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - marker=c0 avail_size_in_buf=23555
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - Changing bps from 0 to 8
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - sof0: picture: 600x882
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - component 0 1:1 id: 0 quant:0
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - component 1 1:1 id: 1 quant:1
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - component 2 1:1 id: 2 quant:1
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - pix fmt id 11111100
Aug 11, 2018 02:20:49.689 [12036] DEBUG - [FFMPEG] - Format yuvj444p chosen by get_format().
Aug 11, 2018 02:20:49.690 [12036] DEBUG - [FFMPEG] - marker parser used 17 bytes (136 bits)
Aug 11, 2018 02:20:49.690 [12036] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=23536
Aug 11, 2018 02:20:49.690 [12036] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:20:49.690 [12036] DEBUG - [FFMPEG] - escaping removed 102 bytes
Aug 11, 2018 02:20:49.690 [12036] DEBUG - [FFMPEG] - marker=da avail_size_in_buf=23337
Aug 11, 2018 02:20:49.690 [12036] DEBUG - [FFMPEG] - marker parser used 23235 bytes (185880 bits)
Aug 11, 2018 02:20:49.690 [12036] DEBUG - [FFMPEG] - marker=d9 avail_size_in_buf=0
Aug 11, 2018 02:20:49.690 [12036] DEBUG - [FFMPEG] - decode frame unused 0 bytes
Aug 11, 2018 02:20:49.690 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 1
Aug 11, 2018 02:20:49.691 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 1
Aug 11, 2018 02:20:49.691 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.692 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.692 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 1
Aug 11, 2018 02:20:49.692 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 1
Aug 11, 2018 02:20:49.692 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 1
Aug 11, 2018 02:20:49.692 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 1
Aug 11, 2018 02:20:49.692 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 1
Aug 11, 2018 02:20:49.692 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 1
Aug 11, 2018 02:20:49.692 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 1
Aug 11, 2018 02:20:49.692 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 1
Aug 11, 2018 02:20:49.692 [12036] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:20:49.701 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.702 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.702 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.702 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.702 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.702 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.702 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.702 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.702 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.705 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:49.708 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.708 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.708 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.709 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.709 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.709 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.709 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.709 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.709 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.717 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.717 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.717 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.717 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.717 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.717 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.717 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.717 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.717 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.723 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.723 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.723 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.723 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.723 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.724 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.724 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.724 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.724 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.729 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.729 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.729 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.729 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.730 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.730 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.730 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.730 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.730 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.735 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.735 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.735 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.735 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.735 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.735 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.735 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.735 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.736 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.741 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.741 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.741 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.741 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.741 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.741 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.741 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.742 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.742 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:20:49.747 [12036] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:20:49.747 [12036] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 95835 bytes read:98304 seeks:0 frames:29
Aug 11, 2018 02:20:49.747 [12036] DEBUG - [MI]  * 28c0ea12f5f26e419b03daa6cc7df4261dc7f1ef [mkv h264/ac3] Duration: 10951744ms, 1916x796 (2.407035) 23.976fps, bitrate=4877kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Batman Vs. Superman Dawn of Justice\Batman vs. Superman Dawn of Justice.mkv")
Aug 11, 2018 02:20:49.748 [12036] DEBUG - [MI] Importing 16 media chapters.
Aug 11, 2018 02:20:49.748 [12036] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:20:49.750 [12036] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Batman Vs. Superman Dawn of Justice\Batman vs. Superman Dawn of Justice.mkv"
Aug 11, 2018 02:20:49.754 [12036] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 0 seeks
Aug 11, 2018 02:20:49.756 [12036] DEBUG - Committing media stream changes for part 41
Aug 11, 2018 02:20:49.761 [12036] DEBUG - Updating media item 41, size=6677748932, metadata_item_id=41
Aug 11, 2018 02:20:49.761 [12036] DEBUG - Updating part with ID=41 [F:\Videos\Movies\Batman Vs. Superman Dawn of Justice\Batman vs. Superman Dawn of Justice.mkv]
Aug 11, 2018 02:20:49.768 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=41&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:49.771 [12036] DEBUG - Analyzing media parts for item 1427 (Imprisoned in Campus): 2096
Aug 11, 2018 02:20:49.774 [12036] DEBUG - [ID 2096] Media part analysis: F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 03 [1080p] [Dual] [khatake2].mkv
Aug 11, 2018 02:20:49.776 [12036] DEBUG - [MI] Opening input file: "F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 03 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:20:49.776 [12036] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 03 [1080p] [Dual] [khatake2].mkv' for reading
Aug 11, 2018 02:20:49.776 [12036] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:20:49.781 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=41&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:49.782 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=41&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:49.793 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=41&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:49.793 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=41&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:49.794 [12036] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:20:49.807 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=41&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:49.811 [12036] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.811 [12036] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.811 [12036] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.811 [12036] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.811 [12036] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:49.816 [12036] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1491153 bytes read:1509959 seeks:2 nb_streams:25
Aug 11, 2018 02:20:49.817 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:49.817 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:49.858 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:49.858 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:49.859 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:49.859 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:49.859 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:49.859 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:49.859 [12036] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:20:49.863 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:49.870 [12036] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Aug 11, 2018 02:20:49.870 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.873 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:49.878 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.888 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.900 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.912 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.922 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:49.931 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.936 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:49.944 [12036] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:20:49.945 [12036] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2993037 bytes read:3017287 seeks:2 frames:58
Aug 11, 2018 02:20:49.946 [12036] DEBUG - [MI]  * 2c515b31b2dd979fb8753666eebfc7c9ce76d247 [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 - 03 [1080p] [Dual] [khatake2].mkv")
Aug 11, 2018 02:20:49.946 [12036] DEBUG - [MI] Importing 6 media chapters.
Aug 11, 2018 02:20:49.947 [12036] DEBUG - [MI] Closing input file: "F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 03 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:20:49.954 [12036] DEBUG - [FFMPEG] - Statistics: 3017287 bytes read, 2 seeks
Aug 11, 2018 02:20:49.956 [12036] DEBUG - Updating media item 2096, size=367408676, metadata_item_id=1427
Aug 11, 2018 02:20:49.956 [12036] DEBUG - Updating part with ID=2096 [F:\Videos\Anime\Code Geass\Season 2\[AnimeRG] Code Geass LOTR R2 - 03 [1080p] [Dual] [khatake2].mkv]
Aug 11, 2018 02:20:49.967 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1427&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:49.973 [12036] DEBUG - It took 31.250000 ms to retrieve 105 items.
Aug 11, 2018 02:20:49.979 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1427&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:49.979 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1427&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:49.992 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1427&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:49.993 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1427&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:20:49.998 [12036] DEBUG - Analyzing media parts for item 260 (Sausage Party): 260
Aug 11, 2018 02:20:49.999 [12036] DEBUG - [ID 260] Media part analysis: F:\Videos\Movies\Sausage Party\Sausage Party.mkv
Aug 11, 2018 02:20:50.001 [12036] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Sausage Party\Sausage Party.mkv"
Aug 11, 2018 02:20:50.001 [12036] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Sausage Party\Sausage Party.mkv' for reading
Aug 11, 2018 02:20:50.001 [12036] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:20:50.007 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1427&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.018 [12036] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:20:50.026 [12036] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.026 [12036] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.027 [12036] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6604 bytes read:33585 seeks:2 nb_streams:2
Aug 11, 2018 02:20:50.028 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:50.028 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:50.029 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:50.029 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:50.030 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:50.030 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.030 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.030 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.030 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.030 [12036] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:20:50.034 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:50.038 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.038 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.038 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.038 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.041 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:50.044 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.044 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.044 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.044 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.052 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.053 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.053 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.053 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.061 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.061 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.061 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.062 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.070 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.070 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.071 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.071 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.074 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.074 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.074 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.074 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.080 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.080 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.080 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.080 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.086 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.086 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.086 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.086 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.093 [12036] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:20:50.093 [12036] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 57619 bytes read:99121 seeks:2 frames:58
Aug 11, 2018 02:20:50.093 [12036] DEBUG - [MI]  * 25a58aef7b2a9693c2f8bccc7840e88df408e121 [mkv h264/dca] Duration: 5325320ms, 1920x1036 (1.853282) 23.976fps, bitrate=3605kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Sausage Party\Sausage Party.mkv")
Aug 11, 2018 02:20:50.094 [12036] DEBUG - [MI] Importing 16 media chapters.
Aug 11, 2018 02:20:50.094 [12036] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:20:50.096 [12036] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:20:50.096 [12036] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:20:50.108 [12036] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:20:50.109 [12036] DEBUG - Updating metadata item (save) (Sausage Party) with ID 260
Aug 11, 2018 02:20:50.118 [12036] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Sausage Party\Sausage Party.mkv"
Aug 11, 2018 02:20:50.122 [12036] DEBUG - [FFMPEG] - Statistics: 99121 bytes read, 2 seeks
Aug 11, 2018 02:20:50.123 [12036] DEBUG - Updating media item 260, size=2399800514, metadata_item_id=260
Aug 11, 2018 02:20:50.123 [12036] DEBUG - Updating part with ID=260 [F:\Videos\Movies\Sausage Party\Sausage Party.mkv]
Aug 11, 2018 02:20:50.130 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=260&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:50.134 [12036] DEBUG - Analyzing media parts for item 11768 (Jupiter Jazz, Part II): 17235
Aug 11, 2018 02:20:50.136 [12036] DEBUG - [ID 17313] Media part analysis: F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 13 - Jupiter Jazz (Part 2) (1080p x265 10bit Joy).mkv
Aug 11, 2018 02:20:50.137 [12036] DEBUG - [MI] Opening input file: "F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 13 - Jupiter Jazz (Part 2) (1080p x265 10bit Joy).mkv"
Aug 11, 2018 02:20:50.137 [12036] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 13 - Jupiter Jazz (Part 2) (1080p x265 10bit Joy).mkv' for reading
Aug 11, 2018 02:20:50.137 [12036] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:20:50.143 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=260&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:50.144 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=260&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:50.159 [12036] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:20:50.161 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=260&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:50.162 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=260&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.167 [12036] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.167 [12036] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.167 [12036] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.167 [12036] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.167 [12036] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.167 [12036] DEBUG - [FFMPEG] - st:5 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.168 [12036] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 196307 bytes read:231108 seeks:2 nb_streams:8
Aug 11, 2018 02:20:50.169 [12036] DEBUG - [FFMPEG] - nal_unit_type: 32(VPS), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:20:50.169 [12036] DEBUG - [FFMPEG] - Decoding VPS
Aug 11, 2018 02:20:50.169 [12036] DEBUG - [FFMPEG] - Main 10 profile bitstream
Aug 11, 2018 02:20:50.169 [12036] DEBUG - [FFMPEG] - nal_unit_type: 33(SPS), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:20:50.170 [12036] DEBUG - [FFMPEG] - Decoding SPS
Aug 11, 2018 02:20:50.170 [12036] DEBUG - [FFMPEG] - Main 10 profile bitstream
Aug 11, 2018 02:20:50.170 [12036] DEBUG - [FFMPEG] - Decoding VUI
Aug 11, 2018 02:20:50.170 [12036] DEBUG - [FFMPEG] - nal_unit_type: 34(PPS), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:20:50.170 [12036] DEBUG - [FFMPEG] - Decoding PPS
Aug 11, 2018 02:20:50.170 [12036] DEBUG - [FFMPEG] - nal_unit_type: 39(SEI_PREFIX), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:20:50.170 [12036] DEBUG - [FFMPEG] - Decoding SEI
Aug 11, 2018 02:20:50.170 [12036] DEBUG - [FFMPEG] - Skipped PREFIX SEI 5
Aug 11, 2018 02:20:50.176 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=260&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.188 [12036] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:20:50.188 [12036] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 567566 bytes read:630349 seeks:2 frames:28
Aug 11, 2018 02:20:50.189 [12036] DEBUG - [MI]  * e0580145ffbaabbe2a56b407c44800b85252ec07 [mkv hevc/aac] Duration: 1482700ms, 1440x1080 (1.333333) 23.976fps, bitrate=1109kbps, audio channels=2, optimizedForStreaming=0 ("F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 13 - Jupiter Jazz (Part 2) (1080p x265 10bit Joy).mkv")
Aug 11, 2018 02:20:50.189 [12036] DEBUG - [MI] Importing 5 media chapters.
Aug 11, 2018 02:20:50.190 [12036] DEBUG - [MI] Closing input file: "F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 13 - Jupiter Jazz (Part 2) (1080p x265 10bit Joy).mkv"
Aug 11, 2018 02:20:50.192 [12036] DEBUG - [FFMPEG] - Statistics: 630349 bytes read, 2 seeks
Aug 11, 2018 02:20:50.194 [12036] DEBUG - Updating media item 17235, size=205546589, metadata_item_id=11768
Aug 11, 2018 02:20:50.194 [12036] DEBUG - Updating part with ID=17313 [F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 13 - Jupiter Jazz (Part 2) (1080p x265 10bit Joy).mkv]
Aug 11, 2018 02:20:50.200 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11768&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:50.210 [12036] DEBUG - It took 31.250000 ms to retrieve 110 items.
Aug 11, 2018 02:20:50.213 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11768&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:50.214 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11768&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:50.238 [12036] DEBUG - Analyzing media parts for item 92 (Finding Dory): 92
Aug 11, 2018 02:20:50.239 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11768&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:50.239 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11768&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.239 [12036] DEBUG - [ID 92] Media part analysis: F:\Videos\Movies\Finding Dory\Finding Dory.mkv
Aug 11, 2018 02:20:50.241 [12036] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Finding Dory\Finding Dory.mkv"
Aug 11, 2018 02:20:50.242 [12036] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Finding Dory\Finding Dory.mkv' for reading
Aug 11, 2018 02:20:50.242 [12036] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:20:50.252 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11768&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.267 [12036] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:20:50.277 [12036] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.277 [12036] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.278 [12036] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7194 bytes read:33585 seeks:2 nb_streams:2
Aug 11, 2018 02:20:50.279 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:50.279 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:50.280 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:50.280 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:50.281 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:50.281 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.281 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.281 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.281 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.281 [12036] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:20:50.285 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:50.288 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.288 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.288 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.289 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.292 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:50.295 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.296 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.296 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.296 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.305 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.305 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.305 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.305 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.316 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.316 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.316 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.316 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.326 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.327 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.327 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.327 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.335 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.335 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.335 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.335 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.344 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.344 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.344 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.344 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.348 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.348 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.348 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.348 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.355 [12036] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:20:50.355 [12036] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 58026 bytes read:99121 seeks:2 frames:58
Aug 11, 2018 02:20:50.356 [12036] DEBUG - [MI]  * e7bbb8694f202394439f4303a2e392c9cdb49cfa [mkv h264/dca] Duration: 5823651ms, 1920x1080 (1.777778) 23.976fps, bitrate=3607kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Finding Dory\Finding Dory.mkv")
Aug 11, 2018 02:20:50.356 [12036] DEBUG - [MI] Importing 29 media chapters.
Aug 11, 2018 02:20:50.356 [12036] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:20:50.358 [12036] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:20:50.358 [12036] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:20:50.370 [12036] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:20:50.370 [12036] DEBUG - Updating metadata item (save) (Finding Dory) with ID 92
Aug 11, 2018 02:20:50.379 [12036] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Finding Dory\Finding Dory.mkv"
Aug 11, 2018 02:20:50.396 [12036] DEBUG - [FFMPEG] - Statistics: 99121 bytes read, 2 seeks
Aug 11, 2018 02:20:50.399 [12036] DEBUG - Updating media item 92, size=2625992775, metadata_item_id=92
Aug 11, 2018 02:20:50.400 [12036] DEBUG - Updating part with ID=92 [F:\Videos\Movies\Finding Dory\Finding Dory.mkv]
Aug 11, 2018 02:20:50.407 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=92&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:50.411 [12036] DEBUG - Analyzing media parts for item 1423 (Zero): 2093
Aug 11, 2018 02:20:50.414 [12036] DEBUG - [ID 2093] Media part analysis: F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 25 [1080p] [Dual] [khatake2].mkv
Aug 11, 2018 02:20:50.416 [12036] DEBUG - [MI] Opening input file: "F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 25 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:20:50.416 [12036] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 25 [1080p] [Dual] [khatake2].mkv' for reading
Aug 11, 2018 02:20:50.416 [12036] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:20:50.422 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=92&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:50.422 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=92&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:50.438 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=92&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:50.441 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=92&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.453 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=92&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.455 [12036] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:20:50.480 [12036] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.480 [12036] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.480 [12036] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.480 [12036] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.480 [12036] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.484 [12036] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1286837 bytes read:1321178 seeks:2 nb_streams:20
Aug 11, 2018 02:20:50.485 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:50.485 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:50.505 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:50.505 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:50.506 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:50.506 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:50.506 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:50.506 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.506 [12036] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:20:50.510 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:50.517 [12036] DEBUG - [FFMPEG] - skip whole frame, skip left: 0
Aug 11, 2018 02:20:50.517 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.520 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:50.525 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.536 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.547 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.561 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.568 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.575 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.588 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.596 [12036] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:20:50.598 [12036] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 2315727 bytes read:2369754 seeks:2 frames:58
Aug 11, 2018 02:20:50.598 [12036] DEBUG - [MI]  * f8b4bf2f2816fabea8fda553653298bbf0b3f14b [mkv h264/aac] Duration: 1556889ms, 1920x1080 (1.777778) 23.976fps, bitrate=1885kbps, audio channels=2, optimizedForStreaming=0 ("F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 25 [1080p] [Dual] [khatake2].mkv")
Aug 11, 2018 02:20:50.599 [12036] DEBUG - [MI] Importing 7 media chapters.
Aug 11, 2018 02:20:50.600 [12036] DEBUG - [MI] Closing input file: "F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 25 [1080p] [Dual] [khatake2].mkv"
Aug 11, 2018 02:20:50.606 [12036] DEBUG - [FFMPEG] - Statistics: 2369754 bytes read, 2 seeks
Aug 11, 2018 02:20:50.608 [12036] DEBUG - Updating media item 2093, size=367024468, metadata_item_id=1423
Aug 11, 2018 02:20:50.609 [12036] DEBUG - Updating part with ID=2093 [F:\Videos\Anime\Code Geass\Season 1\[AnimeRG] Code Geass LOTR R1 - 25 [1080p] [Dual] [khatake2].mkv]
Aug 11, 2018 02:20:50.615 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1423&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:50.628 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1423&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:50.628 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1423&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:50.640 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1423&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:50.641 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1423&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.642 [12036] DEBUG - Analyzing media parts for item 342 (The Good Dinosaur): 342
Aug 11, 2018 02:20:50.643 [12036] DEBUG - [ID 342] Media part analysis: F:\Videos\Movies\The Good Dinosaur\The Good Dinosaur.mkv
Aug 11, 2018 02:20:50.645 [12036] DEBUG - [MI] Opening input file: "F:\Videos\Movies\The Good Dinosaur\The Good Dinosaur.mkv"
Aug 11, 2018 02:20:50.645 [12036] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\The Good Dinosaur\The Good Dinosaur.mkv' for reading
Aug 11, 2018 02:20:50.645 [12036] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:20:50.654 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1423&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.660 [12036] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:20:50.665 [12036] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.665 [12036] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:20:50.665 [12036] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7252 bytes read:33585 seeks:2 nb_streams:2
Aug 11, 2018 02:20:50.666 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:50.666 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:50.667 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:50.667 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:50.668 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:50.668 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.668 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.668 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.668 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.668 [12036] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:20:50.672 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:50.675 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.675 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.675 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.675 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.677 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:50.680 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.681 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.681 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.681 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.687 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.687 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.687 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.687 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.694 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.695 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.695 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.695 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.703 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.703 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.703 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.703 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.709 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.709 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.709 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.709 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:20:50.715 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.716 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.716 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.716 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.718 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.718 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.718 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.718 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.722 [12036] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:20:50.722 [12036] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 57894 bytes read:99121 seeks:2 frames:58
Aug 11, 2018 02:20:50.723 [12036] DEBUG - [MI]  * 9237e391097992f128f2039183a9d814b9400306 [mkv h264/dca] Duration: 5618530ms, 1920x808 (2.376238) 23.976fps, bitrate=3606kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\The Good Dinosaur\The Good Dinosaur.mkv")
Aug 11, 2018 02:20:50.723 [12036] DEBUG - [MI] Importing 29 media chapters.
Aug 11, 2018 02:20:50.724 [12036] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:20:50.725 [12036] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:20:50.725 [12036] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:20:50.737 [12036] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:20:50.738 [12036] DEBUG - Updating metadata item (save) (The Good Dinosaur) with ID 342
Aug 11, 2018 02:20:50.746 [12036] DEBUG - [MI] Closing input file: "F:\Videos\Movies\The Good Dinosaur\The Good Dinosaur.mkv"
Aug 11, 2018 02:20:50.749 [12036] DEBUG - [FFMPEG] - Statistics: 99121 bytes read, 2 seeks
Aug 11, 2018 02:20:50.750 [12036] DEBUG - Updating media item 342, size=2533183514, metadata_item_id=342
Aug 11, 2018 02:20:50.751 [12036] DEBUG - Updating part with ID=342 [F:\Videos\Movies\The Good Dinosaur\The Good Dinosaur.mkv]
Aug 11, 2018 02:20:50.757 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=342&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:50.765 [12036] DEBUG - It took 0.000000 ms to retrieve 150 items.
Aug 11, 2018 02:20:50.769 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=342&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:50.769 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=342&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:50.782 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=342&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:50.783 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=342&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.786 [12036] DEBUG - Analyzing media parts for item 302 (Star Wars: Episode III Revenge of the Sith): 302
Aug 11, 2018 02:20:50.788 [12036] DEBUG - [ID 302] Media part analysis: F:\Videos\Movies\Star Wars Episode III\Star Wars Episode III Revenge of the Sith.mp4
Aug 11, 2018 02:20:50.790 [12036] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Star Wars Episode III\Star Wars Episode III Revenge of the Sith.mp4"
Aug 11, 2018 02:20:50.790 [12036] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Star Wars Episode III\Star Wars Episode III Revenge of the Sith.mp4' for reading
Aug 11, 2018 02:20:50.790 [12036] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:20:50.796 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=342&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:50.801 [12036] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 11, 2018 02:20:50.801 [12036] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Aug 11, 2018 02:20:50.809 [12036] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:20:50.845 [12036] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 1000, duration: 100769510
Aug 11, 2018 02:20:50.867 [12036] DEBUG - [FFMPEG] - Offset DTS by 1000 to make first pts zero.
Aug 11, 2018 02:20:50.888 [12036] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Aug 11, 2018 02:20:50.889 [12036] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:20:50.926 [12036] DEBUG - [FFMPEG] - Processing st: 1, edit list 0 - media time: 0, duration: 403484640
Aug 11, 2018 02:20:50.956 [12036] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:20:50.956 [12036] DEBUG - [FFMPEG] - Processing st: 2, edit list 0 - media time: 0, duration: 8405857
Aug 11, 2018 02:20:50.957 [12036] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 2869745302 bytes read:7062154 seeks:3 nb_streams:3
Aug 11, 2018 02:20:50.958 [12036] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:20:50.958 [12036] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:20:50.962 [12036] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:20:50.962 [12036] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:20:50.962 [12036] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:20:50.965 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:50.967 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.969 [12036] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:20:50.971 [12036] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:20:50.978 [12036] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:20:50.978 [12036] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3194 bytes read:7094922 seeks:4 frames:4
Aug 11, 2018 02:20:50.978 [12036] DEBUG - [MI] Opening "F:\Videos\Movies\Star Wars Episode III\Star Wars Episode III Revenge of the Sith.mp4" for MP4 atom parsing
Aug 11, 2018 02:20:50.978 [12036] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 32 at offset 0
Aug 11, 2018 02:20:50.978 [12036] DEBUG - Mp4AtomParser: Skipping free atom of size 8 at offset 32
Aug 11, 2018 02:20:50.978 [12036] DEBUG - Mp4AtomParser: Skipping mdat atom of size 2862748644 at offset 40
Aug 11, 2018 02:20:50.978 [12036] DEBUG - Mp4AtomParser: Found moov atom of size 6996618 at offset 2862748684
Aug 11, 2018 02:20:50.987 [12036] DEBUG - Mp4AtomParser: Found trak atom of size 2818097 at offset 2862748800
Aug 11, 2018 02:20:50.987 [12036] DEBUG - Mp4AtomParser: Found trak atom of size 4176133 at offset 2865566897
Aug 11, 2018 02:20:50.987 [12036] DEBUG - Mp4AtomParser: Found trak atom of size 911 at offset 2869743030
Aug 11, 2018 02:20:50.987 [12036] DEBUG - Mp4AtomParser: Found mdia atom of size 2817941 at offset 2862748956
Aug 11, 2018 02:20:50.987 [12036] DEBUG - Mp4AtomParser: Found minf atom of size 2817856 at offset 2862749041
Aug 11, 2018 02:20:50.987 [12036] DEBUG - Mp4AtomParser: Found stbl atom of size 2817792 at offset 2862749105
Aug 11, 2018 02:20:50.988 [12036] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 2862748936
Aug 11, 2018 02:20:50.988 [12036] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 2862748944
Aug 11, 2018 02:20:50.988 [12036] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Aug 11, 2018 02:20:50.988 [12036] DEBUG - Mp4AtomParser: Found mdia atom of size 4175977 at offset 2865567053
Aug 11, 2018 02:20:50.988 [12036] DEBUG - Mp4AtomParser: Found minf atom of size 4175892 at offset 2865567138
Aug 11, 2018 02:20:50.988 [12036] DEBUG - Mp4AtomParser: Found stbl atom of size 4175832 at offset 2865567198
Aug 11, 2018 02:20:50.988 [12036] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 2865567033
Aug 11, 2018 02:20:50.988 [12036] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 2865567041
Aug 11, 2018 02:20:50.988 [12036] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Aug 11, 2018 02:20:50.988 [12036] DEBUG - Mp4AtomParser: Found mdia atom of size 775 at offset 2869743166
Aug 11, 2018 02:20:50.988 [12036] DEBUG - Mp4AtomParser: Found minf atom of size 687 at offset 2869743254
Aug 11, 2018 02:20:50.988 [12036] DEBUG - Mp4AtomParser: Found stbl atom of size 567 at offset 2869743374
Aug 11, 2018 02:20:50.990 [12036] DEBUG - [MI]  * 917c66d6d17411e59d16429c0c711b403a612526 [mp4 h264/aac] Duration: 8405930ms, 1920x824 (2.330097) 23.976fps, bitrate=2731kbps, audio channels=8, optimizedForStreaming=0 ("F:\Videos\Movies\Star Wars Episode III\Star Wars Episode III Revenge of the Sith.mp4")
Aug 11, 2018 02:20:50.991 [12036] DEBUG - [MI] Importing 50 media chapters.
Aug 11, 2018 02:20:50.991 [12036] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:20:50.994 [12036] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Star Wars Episode III\Star Wars Episode III Revenge of the Sith.mp4"
Aug 11, 2018 02:20:50.999 [12036] DEBUG - [FFMPEG] - Statistics: 7094922 bytes read, 4 seeks
Aug 11, 2018 02:20:51.001 [12036] DEBUG - Updating media item 302, size=2869745302, metadata_item_id=302
Aug 11, 2018 02:20:51.002 [12036] DEBUG - Updating part with ID=302 [F:\Videos\Movies\Star Wars Episode III\Star Wars Episode III Revenge of the Sith.mp4]
Aug 11, 2018 02:20:51.008 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=302&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:51.021 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=302&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:20:51.022 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=302&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:51.036 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=302&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:20:51.036 [1152] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=302&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:20:51.049 [1152] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=302&metadataType=1&state=-1&mediaState=idle
