Aug 11, 2018 02:22:20.202 [3172] INFO - Plex Media Scanner v1.13.5.5291-6fa5e50a8 - Microsoft PC x64 - build: windows-i386 - GMT -07:00
Aug 11, 2018 02:22:20.203 [3172] INFO - Windows version: 6.2 (Build 9200), language en-US
Aug 11, 2018 02:22:20.203 [3172] 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:22:20.203 [3172] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Scanner.exe" --analyze --item 79,298,78,175,204,404,11781,11775,335 --log-file-suffix " Analysis" 
Aug 11, 2018 02:22:20.202 [6184] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Aug 11, 2018 02:22:20.364 [6184] DEBUG - Analyzing media parts for item 79 (Army of Darkness): 79
Aug 11, 2018 02:22:20.365 [6184] DEBUG - [ID 79] Media part analysis: F:\Videos\Movies\Evil Dead 3 Army of Darkness\Army Of Darkness.mkv
Aug 11, 2018 02:22:20.367 [6184] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Evil Dead 3 Army of Darkness\Army Of Darkness.mkv"
Aug 11, 2018 02:22:20.367 [6184] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Evil Dead 3 Army of Darkness\Army Of Darkness.mkv' for reading
Aug 11, 2018 02:22:20.367 [6184] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:22:20.402 [6184] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:22:20.413 [6184] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.413 [6184] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.413 [6184] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.413 [6184] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.413 [6184] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.413 [6184] DEBUG - [FFMPEG] - st:5 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.413 [6184] DEBUG - [FFMPEG] - st:6 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.414 [6184] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 147251 bytes read:233024 seeks:4 nb_streams:8
Aug 11, 2018 02:22:20.415 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:20.415 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:20.421 [6184] DEBUG - [FFMPEG] - marker=d8 avail_size_in_buf=140007
Aug 11, 2018 02:22:20.421 [6184] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:22:20.421 [6184] DEBUG - [FFMPEG] - marker=e0 avail_size_in_buf=140005
Aug 11, 2018 02:22:20.421 [6184] DEBUG - [FFMPEG] - marker parser used 16 bytes (128 bits)
Aug 11, 2018 02:22:20.421 [6184] DEBUG - [FFMPEG] - marker=db avail_size_in_buf=139987
Aug 11, 2018 02:22:20.421 [6184] DEBUG - [FFMPEG] - index=0
Aug 11, 2018 02:22:20.421 [6184] DEBUG - [FFMPEG] - qscale[0]: 2
Aug 11, 2018 02:22:20.421 [6184] DEBUG - [FFMPEG] - marker parser used 67 bytes (536 bits)
Aug 11, 2018 02:22:20.421 [6184] DEBUG - [FFMPEG] - marker=db avail_size_in_buf=139918
Aug 11, 2018 02:22:20.421 [6184] DEBUG - [FFMPEG] - index=1
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - qscale[1]: 5
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - marker parser used 67 bytes (536 bits)
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - marker=c0 avail_size_in_buf=139849
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - Changing bps from 0 to 8
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - sof0: picture: 537x800
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - component 0 1:1 id: 0 quant:0
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - component 1 1:1 id: 1 quant:1
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - component 2 1:1 id: 2 quant:1
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - pix fmt id 11111100
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - Format yuvj444p chosen by get_format().
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - marker parser used 17 bytes (136 bits)
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=139830
Aug 11, 2018 02:22:20.422 [6184] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=139797
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=139614
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=139581
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - escaping removed 507 bytes
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - marker=da avail_size_in_buf=139398
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - marker parser used 138891 bytes (1111128 bits)
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - marker=d9 avail_size_in_buf=0
Aug 11, 2018 02:22:20.423 [6184] DEBUG - [FFMPEG] - decode frame unused 0 bytes
Aug 11, 2018 02:22:20.424 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:20.424 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:20.424 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:20.424 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:20.425 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:20.425 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:20.425 [6184] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:22:20.429 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:20.435 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.439 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:20.443 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.452 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.460 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.470 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.480 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.485 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.493 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.499 [6184] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:22:20.499 [6184] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 188814 bytes read:298560 seeks:4 frames:41
Aug 11, 2018 02:22:20.499 [6184] DEBUG - [MI]  * 52dc7077933acc6e5d5290ee8f90e570765aa22a [mkv h264/eac3] Duration: 5782208ms, 1920x1080 (1.777778) 23.976fps, bitrate=5593kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Evil Dead 3 Army of Darkness\Army Of Darkness.mkv")
Aug 11, 2018 02:22:20.500 [6184] DEBUG - [MI] Importing 16 media chapters.
Aug 11, 2018 02:22:20.502 [6184] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Evil Dead 3 Army of Darkness\Army Of Darkness.mkv"
Aug 11, 2018 02:22:20.507 [6184] DEBUG - [FFMPEG] - Statistics: 298560 bytes read, 4 seeks
Aug 11, 2018 02:22:20.509 [6184] DEBUG - Committing media stream changes for part 79
Aug 11, 2018 02:22:20.515 [6184] DEBUG - Updating media item 79, size=4042992891, metadata_item_id=79
Aug 11, 2018 02:22:20.515 [6184] DEBUG - Updating part with ID=79 [F:\Videos\Movies\Evil Dead 3 Army of Darkness\Army Of Darkness.mkv]
Aug 11, 2018 02:22:20.522 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=79&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:20.529 [6184] DEBUG - It took 0.000000 ms to retrieve 106 items.
Aug 11, 2018 02:22:20.537 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=79&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:20.537 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=79&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:20.550 [6184] DEBUG - Analyzing media parts for item 298 (Star Trek Beyond): 298
Aug 11, 2018 02:22:20.551 [6184] DEBUG - [ID 298] Media part analysis: F:\Videos\Movies\Star Trek Beyond\Star Trek Beyond.mkv
Aug 11, 2018 02:22:20.552 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=79&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:20.553 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=79&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:20.555 [6184] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Star Trek Beyond\Star Trek Beyond.mkv"
Aug 11, 2018 02:22:20.555 [6184] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Star Trek Beyond\Star Trek Beyond.mkv' for reading
Aug 11, 2018 02:22:20.555 [6184] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:22:20.566 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=79&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:20.591 [6184] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:22:20.601 [6184] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.601 [6184] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.601 [6184] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6468 bytes read:33585 seeks:2 nb_streams:2
Aug 11, 2018 02:22:20.602 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:20.602 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:20.604 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:20.604 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:20.605 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:20.605 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:20.605 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:20.605 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:20.605 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:20.606 [6184] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:22:20.609 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:20.611 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.611 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.611 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.611 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.614 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:20.616 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.616 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.616 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.616 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.623 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.623 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.623 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.623 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.630 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.630 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.630 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.630 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.636 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.637 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.637 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.637 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.642 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.642 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.642 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.643 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.648 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.648 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.648 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.648 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.651 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.651 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.651 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.651 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.655 [6184] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:22:20.655 [6184] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 57080 bytes read:99121 seeks:2 frames:58
Aug 11, 2018 02:22:20.655 [6184] DEBUG - [MI]  * f6b154828640a83d019b36b15f3bc528eb8aea9f [mkv h264/dca] Duration: 7342168ms, 1920x804 (2.388060) 23.976fps, bitrate=3607kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Star Trek Beyond\Star Trek Beyond.mkv")
Aug 11, 2018 02:22:20.656 [6184] DEBUG - [MI] Importing 14 media chapters.
Aug 11, 2018 02:22:20.656 [6184] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:22:20.658 [6184] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:22:20.658 [6184] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:22:20.670 [6184] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:22:20.671 [6184] DEBUG - Updating metadata item (save) (Star Trek Beyond) with ID 298
Aug 11, 2018 02:22:20.679 [6184] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Star Trek Beyond\Star Trek Beyond.mkv"
Aug 11, 2018 02:22:20.682 [6184] DEBUG - [FFMPEG] - Statistics: 99121 bytes read, 2 seeks
Aug 11, 2018 02:22:20.683 [6184] DEBUG - Updating media item 298, size=3310556068, metadata_item_id=298
Aug 11, 2018 02:22:20.683 [6184] DEBUG - Updating part with ID=298 [F:\Videos\Movies\Star Trek Beyond\Star Trek Beyond.mkv]
Aug 11, 2018 02:22:20.689 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=298&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:20.702 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=298&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:20.703 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=298&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:20.708 [6184] DEBUG - Analyzing media parts for item 78 (Evil Dead II): 78
Aug 11, 2018 02:22:20.709 [6184] DEBUG - [ID 78] Media part analysis: F:\Videos\Movies\Evil Dead 2\Evil Dead II.mkv
Aug 11, 2018 02:22:20.710 [6184] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Evil Dead 2\Evil Dead II.mkv"
Aug 11, 2018 02:22:20.710 [6184] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Evil Dead 2\Evil Dead II.mkv' for reading
Aug 11, 2018 02:22:20.710 [6184] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:22:20.716 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=298&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:20.717 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=298&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:20.729 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=298&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:20.774 [6184] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:22:20.791 [6184] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.791 [6184] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.791 [6184] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.791 [6184] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:20.792 [6184] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 96170 bytes read:174901 seeks:4 nb_streams:5
Aug 11, 2018 02:22:20.792 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:20.792 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:20.795 [6184] DEBUG - [FFMPEG] - marker=d8 avail_size_in_buf=89321
Aug 11, 2018 02:22:20.795 [6184] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:22:20.795 [6184] DEBUG - [FFMPEG] - marker=e0 avail_size_in_buf=89319
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - marker parser used 16 bytes (128 bits)
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - marker=db avail_size_in_buf=89301
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - index=0
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - qscale[0]: 2
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - marker parser used 67 bytes (536 bits)
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - marker=db avail_size_in_buf=89232
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - index=1
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - qscale[1]: 5
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - marker parser used 67 bytes (536 bits)
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - marker=c0 avail_size_in_buf=89163
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - Changing bps from 0 to 8
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - sof0: picture: 518x800
Aug 11, 2018 02:22:20.796 [6184] DEBUG - [FFMPEG] - component 0 1:1 id: 0 quant:0
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - component 1 1:1 id: 1 quant:1
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - component 2 1:1 id: 2 quant:1
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - pix fmt id 11111100
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - Format yuvj444p chosen by get_format().
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - marker parser used 17 bytes (136 bits)
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=89144
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=89111
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=88928
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - marker=c4 avail_size_in_buf=88895
Aug 11, 2018 02:22:20.797 [6184] DEBUG - [FFMPEG] - marker parser used 0 bytes (0 bits)
Aug 11, 2018 02:22:20.798 [6184] DEBUG - [FFMPEG] - escaping removed 213 bytes
Aug 11, 2018 02:22:20.798 [6184] DEBUG - [FFMPEG] - marker=da avail_size_in_buf=88712
Aug 11, 2018 02:22:20.798 [6184] DEBUG - [FFMPEG] - marker parser used 88499 bytes (707992 bits)
Aug 11, 2018 02:22:20.798 [6184] DEBUG - [FFMPEG] - marker=d9 avail_size_in_buf=0
Aug 11, 2018 02:22:20.798 [6184] DEBUG - [FFMPEG] - decode frame unused 0 bytes
Aug 11, 2018 02:22:20.798 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:20.798 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:20.799 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:20.799 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:20.799 [6184] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:22:20.802 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:20.807 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.810 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:20.813 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.822 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.832 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.842 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.848 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.851 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:20.857 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:20.862 [6184] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:22:20.863 [6184] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 138166 bytes read:240437 seeks:4 frames:41
Aug 11, 2018 02:22:20.863 [6184] DEBUG - [MI]  * daa4225e50d5ea5a7cf270a443b7ee5db107e77f [mkv h264/eac3] Duration: 5056192ms, 1920x1040 (1.846154) 23.976fps, bitrate=5601kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Evil Dead 2\Evil Dead II.mkv")
Aug 11, 2018 02:22:20.863 [6184] DEBUG - [MI] Importing 16 media chapters.
Aug 11, 2018 02:22:20.865 [6184] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Evil Dead 2\Evil Dead II.mkv"
Aug 11, 2018 02:22:20.869 [6184] DEBUG - [FFMPEG] - Statistics: 240437 bytes read, 4 seeks
Aug 11, 2018 02:22:20.870 [6184] DEBUG - Committing media stream changes for part 78
Aug 11, 2018 02:22:20.874 [6184] DEBUG - Updating media item 78, size=3540572300, metadata_item_id=78
Aug 11, 2018 02:22:20.874 [6184] DEBUG - Updating part with ID=78 [F:\Videos\Movies\Evil Dead 2\Evil Dead II.mkv]
Aug 11, 2018 02:22:20.882 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:20.905 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:20.906 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:20.909 [6184] DEBUG - Analyzing media parts for item 175 (Kung Fu Panda): 175
Aug 11, 2018 02:22:20.910 [6184] DEBUG - [ID 175] Media part analysis: F:\Videos\Movies\Kung Fu Panda\Kung Fu Panda.mp4
Aug 11, 2018 02:22:20.912 [6184] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Kung Fu Panda\Kung Fu Panda.mp4"
Aug 11, 2018 02:22:20.913 [6184] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Kung Fu Panda\Kung Fu Panda.mp4' for reading
Aug 11, 2018 02:22:20.913 [6184] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:22:20.926 [6184] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Aug 11, 2018 02:22:20.926 [6184] DEBUG - [FFMPEG] - ISO: File Type Major Brand: isom
Aug 11, 2018 02:22:20.927 [6184] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:22:20.928 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:20.928 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:20.940 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:20.948 [6184] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 2002, duration: 132707560
Aug 11, 2018 02:22:20.960 [6184] DEBUG - [FFMPEG] - Offset DTS by 2002 to make first pts zero.
Aug 11, 2018 02:22:20.975 [6184] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Aug 11, 2018 02:22:20.976 [6184] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:22:20.983 [6184] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Aug 11, 2018 02:22:21.009 [6184] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7904702692 bytes read:3084841 seeks:1 nb_streams:3
Aug 11, 2018 02:22:21.010 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:21.010 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:21.015 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.016 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.016 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.016 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.016 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.016 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.016 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.016 [6184] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:22:21.019 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:21.021 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.022 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.022 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.022 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.022 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.024 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:21.027 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.027 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:22:21.027 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:22:21.027 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:22:21.027 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:22:21.033 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.033 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.034 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.034 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.034 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.040 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.040 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.040 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.041 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.041 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.048 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.049 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.049 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.049 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.049 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.051 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.051 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:22:21.051 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:22:21.052 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:22:21.052 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 1
Aug 11, 2018 02:22:21.055 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.056 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.056 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.056 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.056 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.060 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.060 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.060 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.060 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.061 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.068 [6184] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:22:21.068 [6184] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3110395 bytes read:3117609 seeks:2 frames:29
Aug 11, 2018 02:22:21.068 [6184] DEBUG - [MI] Opening "F:\Videos\Movies\Kung Fu Panda\Kung Fu Panda.mp4" for MP4 atom parsing
Aug 11, 2018 02:22:21.069 [6184] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 24 at offset 0
Aug 11, 2018 02:22:21.069 [6184] DEBUG - Mp4AtomParser: Found moov atom of size 3083100 at offset 24
Aug 11, 2018 02:22:21.072 [6184] DEBUG - Mp4AtomParser: Found trak atom of size 1657592 at offset 161
Aug 11, 2018 02:22:21.073 [6184] DEBUG - Mp4AtomParser: Found trak atom of size 194186 at offset 1657753
Aug 11, 2018 02:22:21.073 [6184] DEBUG - Mp4AtomParser: Found trak atom of size 1230953 at offset 1851939
Aug 11, 2018 02:22:21.073 [6184] DEBUG - Mp4AtomParser: Found mdia atom of size 1657456 at offset 297
Aug 11, 2018 02:22:21.073 [6184] DEBUG - Mp4AtomParser: Found minf atom of size 1657378 at offset 375
Aug 11, 2018 02:22:21.073 [6184] DEBUG - Mp4AtomParser: Found stbl atom of size 1657314 at offset 439
Aug 11, 2018 02:22:21.073 [6184] DEBUG - Mp4AtomParser: Found co64 atom of size 96440 at offset 1561313
Aug 11, 2018 02:22:21.073 [6184] DEBUG - [MI] File has co64 atom with size 96440 at offset 1561313
Aug 11, 2018 02:22:21.074 [6184] DEBUG - [MI]  * cfc309d0bdc755e80fd2ddf019d248e29c4e5c06 [mp4 h264/ac3] Duration: 5529557ms, 1920x816 (2.352941) 23.976fps, bitrate=11436kbps, audio channels=6, optimizedForStreaming=1 ("F:\Videos\Movies\Kung Fu Panda\Kung Fu Panda.mp4")
Aug 11, 2018 02:22:21.075 [6184] DEBUG - [MI] Importing 23 media chapters.
Aug 11, 2018 02:22:21.075 [6184] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:22:21.076 [6184] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:22:21.076 [6184] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:22:21.088 [6184] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Aug 11, 2018 02:22:21.088 [6184] DEBUG - Updating metadata item (save) (Kung Fu Panda) with ID 175
Aug 11, 2018 02:22:21.097 [6184] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Kung Fu Panda\Kung Fu Panda.mp4"
Aug 11, 2018 02:22:21.102 [6184] DEBUG - [FFMPEG] - Statistics: 3117609 bytes read, 2 seeks
Aug 11, 2018 02:22:21.104 [6184] DEBUG - Updating media item 175, size=7904702737, metadata_item_id=175
Aug 11, 2018 02:22:21.104 [6184] DEBUG - Updating part with ID=175 [F:\Videos\Movies\Kung Fu Panda\Kung Fu Panda.mp4]
Aug 11, 2018 02:22:21.110 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=175&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.123 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=175&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.123 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=175&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.129 [6184] DEBUG - Analyzing media parts for item 204 (Life of Brian): 204
Aug 11, 2018 02:22:21.131 [6184] DEBUG - [ID 204] Media part analysis: F:\Videos\Movies\Monty Python Life of Brian\Monty Python's Life of Brian.avi
Aug 11, 2018 02:22:21.132 [6184] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Monty Python Life of Brian\Monty Python's Life of Brian.avi"
Aug 11, 2018 02:22:21.132 [6184] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Monty Python Life of Brian\Monty Python's Life of Brian.avi' for reading
Aug 11, 2018 02:22:21.132 [6184] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:22:21.137 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=175&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.144 [6184] DEBUG - [FFMPEG] - Format avi probed with size=2048 and score=100
Aug 11, 2018 02:22:21.145 [6184] DEBUG - [FFMPEG] - use odml:1
Aug 11, 2018 02:22:21.145 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=175&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.145 [6184] DEBUG - [FFMPEG] - st:1 removing common factor 384 from timebase
Aug 11, 2018 02:22:21.168 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=175&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.415 [6184] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 10252 bytes read:6038744 seeks:4 nb_streams:2
Aug 11, 2018 02:22:21.426 [6184] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:22:21.427 [6184] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:22:21.427 [6184] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 15111 bytes read:6038744 seeks:4 frames:22
Aug 11, 2018 02:22:21.427 [6184] DEBUG - [MI]  * de49fe8392139469604da055f11646bac8679b89 [avi mpeg4/mp3] Duration: 5624041ms, 640x336 (1.904762) 23.976fps, bitrate=1043kbps, audio channels=2, optimizedForStreaming=0 ("F:\Videos\Movies\Monty Python Life of Brian\Monty Python's Life of Brian.avi")
Aug 11, 2018 02:22:21.428 [6184] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:22:21.428 [6184] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Monty Python Life of Brian\Monty Python's Life of Brian.avi"
Aug 11, 2018 02:22:21.430 [6184] DEBUG - [FFMPEG] - Statistics: 6038744 bytes read, 4 seeks
Aug 11, 2018 02:22:21.431 [6184] DEBUG - Updating media item 204, size=733722624, metadata_item_id=204
Aug 11, 2018 02:22:21.431 [6184] DEBUG - Updating part with ID=204 [F:\Videos\Movies\Monty Python Life of Brian\Monty Python's Life of Brian.avi]
Aug 11, 2018 02:22:21.437 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=204&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.444 [6184] DEBUG - It took 31.250000 ms to retrieve 118 items.
Aug 11, 2018 02:22:21.450 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=204&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.450 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=204&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.460 [6184] DEBUG - Analyzing media parts for item 404 (Warcraft): 404
Aug 11, 2018 02:22:21.462 [6184] DEBUG - [ID 404] Media part analysis: F:\Videos\Movies\Warcraft\Warcraft.mkv
Aug 11, 2018 02:22:21.463 [6184] DEBUG - [MI] Opening input file: "F:\Videos\Movies\Warcraft\Warcraft.mkv"
Aug 11, 2018 02:22:21.464 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=204&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.464 [6184] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\Warcraft\Warcraft.mkv' for reading
Aug 11, 2018 02:22:21.464 [6184] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:22:21.464 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=204&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.473 [6184] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:22:21.479 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=204&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.480 [6184] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.481 [6184] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.481 [6184] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6766 bytes read:33585 seeks:2 nb_streams:2
Aug 11, 2018 02:22:21.482 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:21.482 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:21.483 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:21.483 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:21.483 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.483 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.484 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.484 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.484 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.484 [6184] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:22:21.487 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:21.490 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.490 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.490 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.490 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.492 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:21.496 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.496 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.496 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.496 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.504 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.504 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.504 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.504 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.511 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.512 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.512 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.512 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.520 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.520 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.520 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.520 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.527 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.527 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.527 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.527 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.533 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.533 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.533 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.533 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.536 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.536 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.536 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.536 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.541 [6184] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:22:21.541 [6184] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 57451 bytes read:99121 seeks:2 frames:58
Aug 11, 2018 02:22:21.542 [6184] DEBUG - [MI]  * 15cdea296ee0fe3199bfe223d8424de5f467fe29 [mkv h264/dca] Duration: 7387382ms, 1920x792 (2.424242) 23.976fps, bitrate=3606kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\Warcraft\Warcraft.mkv")
Aug 11, 2018 02:22:21.542 [6184] DEBUG - [MI] Importing 20 media chapters.
Aug 11, 2018 02:22:21.542 [6184] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:22:21.544 [6184] DEBUG - [MI] Closing input file: "F:\Videos\Movies\Warcraft\Warcraft.mkv"
Aug 11, 2018 02:22:21.548 [6184] DEBUG - [FFMPEG] - Statistics: 99121 bytes read, 2 seeks
Aug 11, 2018 02:22:21.549 [6184] DEBUG - Updating media item 404, size=3330554475, metadata_item_id=404
Aug 11, 2018 02:22:21.550 [6184] DEBUG - Updating part with ID=404 [F:\Videos\Movies\Warcraft\Warcraft.mkv]
Aug 11, 2018 02:22:21.556 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=404&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.561 [6184] DEBUG - Analyzing media parts for item 11781 (The Real Folk Blues (2)): 17248
Aug 11, 2018 02:22:21.563 [6184] DEBUG - [ID 17326] Media part analysis: F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 26 - The Real Folk Blues (Part 2) (1080p x265 10bit Joy).mkv
Aug 11, 2018 02:22:21.564 [6184] DEBUG - [MI] Opening input file: "F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 26 - The Real Folk Blues (Part 2) (1080p x265 10bit Joy).mkv"
Aug 11, 2018 02:22:21.564 [6184] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 26 - The Real Folk Blues (Part 2) (1080p x265 10bit Joy).mkv' for reading
Aug 11, 2018 02:22:21.565 [6184] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:22:21.568 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=404&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.569 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=404&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.581 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=404&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.582 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=404&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.597 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=404&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.601 [6184] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:22:21.605 [6184] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.605 [6184] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.605 [6184] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.605 [6184] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.605 [6184] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.605 [6184] DEBUG - [FFMPEG] - st:5 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.606 [6184] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 196338 bytes read:231200 seeks:2 nb_streams:8
Aug 11, 2018 02:22:21.607 [6184] DEBUG - [FFMPEG] - nal_unit_type: 32(VPS), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:22:21.607 [6184] DEBUG - [FFMPEG] - Decoding VPS
Aug 11, 2018 02:22:21.607 [6184] DEBUG - [FFMPEG] - Main 10 profile bitstream
Aug 11, 2018 02:22:21.607 [6184] DEBUG - [FFMPEG] - nal_unit_type: 33(SPS), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:22:21.607 [6184] DEBUG - [FFMPEG] - Decoding SPS
Aug 11, 2018 02:22:21.607 [6184] DEBUG - [FFMPEG] - Main 10 profile bitstream
Aug 11, 2018 02:22:21.607 [6184] DEBUG - [FFMPEG] - Decoding VUI
Aug 11, 2018 02:22:21.607 [6184] DEBUG - [FFMPEG] - nal_unit_type: 34(PPS), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:22:21.608 [6184] DEBUG - [FFMPEG] - Decoding PPS
Aug 11, 2018 02:22:21.608 [6184] DEBUG - [FFMPEG] - nal_unit_type: 39(SEI_PREFIX), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:22:21.608 [6184] DEBUG - [FFMPEG] - Decoding SEI
Aug 11, 2018 02:22:21.608 [6184] DEBUG - [FFMPEG] - Skipped PREFIX SEI 5
Aug 11, 2018 02:22:21.615 [6184] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:22:21.616 [6184] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 202571 bytes read:263968 seeks:2 frames:20
Aug 11, 2018 02:22:21.616 [6184] DEBUG - [MI]  * 34d3bed86202c6df38b9b1766d87aef539e48377 [mkv hevc/aac] Duration: 1457994ms, 1440x1080 (1.333333) 23.976fps, bitrate=1184kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 26 - The Real Folk Blues (Part 2) (1080p x265 10bit Joy).mkv")
Aug 11, 2018 02:22:21.617 [6184] DEBUG - [MI] Importing 4 media chapters.
Aug 11, 2018 02:22:21.618 [6184] DEBUG - [MI] Closing input file: "F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 26 - The Real Folk Blues (Part 2) (1080p x265 10bit Joy).mkv"
Aug 11, 2018 02:22:21.620 [6184] DEBUG - [FFMPEG] - Statistics: 263968 bytes read, 2 seeks
Aug 11, 2018 02:22:21.622 [6184] DEBUG - Updating media item 17248, size=215887707, metadata_item_id=11781
Aug 11, 2018 02:22:21.622 [6184] DEBUG - Updating part with ID=17326 [F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 26 - The Real Folk Blues (Part 2) (1080p x265 10bit Joy).mkv]
Aug 11, 2018 02:22:21.628 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11781&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.630 [6184] DEBUG - Analyzing media parts for item 11775 (Pierrot Le Fou): 17242
Aug 11, 2018 02:22:21.632 [6184] DEBUG - [ID 17320] Media part analysis: F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 20 - Pierrot Le Fou (1080p x265 10bit Joy).mkv
Aug 11, 2018 02:22:21.634 [6184] DEBUG - [MI] Opening input file: "F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 20 - Pierrot Le Fou (1080p x265 10bit Joy).mkv"
Aug 11, 2018 02:22:21.634 [6184] DEBUG - [FFMPEG] - Opening 'F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 20 - Pierrot Le Fou (1080p x265 10bit Joy).mkv' for reading
Aug 11, 2018 02:22:21.634 [6184] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:22:21.640 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11781&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.641 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11781&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.645 [6184] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:22:21.654 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11781&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.654 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11781&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.655 [6184] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.655 [6184] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.655 [6184] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.655 [6184] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.655 [6184] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.656 [6184] DEBUG - [FFMPEG] - st:5 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.657 [6184] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 196316 bytes read:231117 seeks:2 nb_streams:8
Aug 11, 2018 02:22:21.657 [6184] DEBUG - [FFMPEG] - nal_unit_type: 32(VPS), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:22:21.657 [6184] DEBUG - [FFMPEG] - Decoding VPS
Aug 11, 2018 02:22:21.658 [6184] DEBUG - [FFMPEG] - Main 10 profile bitstream
Aug 11, 2018 02:22:21.658 [6184] DEBUG - [FFMPEG] - nal_unit_type: 33(SPS), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:22:21.658 [6184] DEBUG - [FFMPEG] - Decoding SPS
Aug 11, 2018 02:22:21.658 [6184] DEBUG - [FFMPEG] - Main 10 profile bitstream
Aug 11, 2018 02:22:21.658 [6184] DEBUG - [FFMPEG] - Decoding VUI
Aug 11, 2018 02:22:21.658 [6184] DEBUG - [FFMPEG] - nal_unit_type: 34(PPS), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:22:21.658 [6184] DEBUG - [FFMPEG] - Decoding PPS
Aug 11, 2018 02:22:21.658 [6184] DEBUG - [FFMPEG] - nal_unit_type: 39(SEI_PREFIX), nuh_layer_id: 0, temporal_id: 0
Aug 11, 2018 02:22:21.658 [6184] DEBUG - [FFMPEG] - Decoding SEI
Aug 11, 2018 02:22:21.659 [6184] DEBUG - [FFMPEG] - Skipped PREFIX SEI 5
Aug 11, 2018 02:22:21.665 [6184] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:22:21.666 [6184] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 208435 bytes read:263885 seeks:2 frames:19
Aug 11, 2018 02:22:21.666 [6184] DEBUG - [MI]  * e16b2be747134a324ca28f313f2e6f87c32d3399 [mkv hevc/aac] Duration: 1479498ms, 1440x1080 (1.333333) 23.976fps, bitrate=1191kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 20 - Pierrot Le Fou (1080p x265 10bit Joy).mkv")
Aug 11, 2018 02:22:21.667 [6184] DEBUG - [MI] Importing 5 media chapters.
Aug 11, 2018 02:22:21.667 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11781&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.668 [6184] DEBUG - [MI] Closing input file: "F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 20 - Pierrot Le Fou (1080p x265 10bit Joy).mkv"
Aug 11, 2018 02:22:21.669 [6184] DEBUG - [FFMPEG] - Statistics: 263885 bytes read, 2 seeks
Aug 11, 2018 02:22:21.670 [6184] DEBUG - Updating media item 17242, size=220290843, metadata_item_id=11775
Aug 11, 2018 02:22:21.671 [6184] DEBUG - Updating part with ID=17320 [F:\Videos\Anime (Dubbed)\Cowboy Bebop\Cowboy Bebop 20 - Pierrot Le Fou (1080p x265 10bit Joy).mkv]
Aug 11, 2018 02:22:21.677 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11775&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.682 [6184] DEBUG - It took 0.000000 ms to retrieve 109 items.
Aug 11, 2018 02:22:21.689 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11775&metadataType=4&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.689 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11775&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.690 [6184] DEBUG - Analyzing media parts for item 335 (The Danish Girl): 335
Aug 11, 2018 02:22:21.692 [6184] DEBUG - [ID 335] Media part analysis: F:\Videos\Movies\The Danish Girl\The Danish Girl.mkv
Aug 11, 2018 02:22:21.693 [6184] DEBUG - [MI] Opening input file: "F:\Videos\Movies\The Danish Girl\The Danish Girl.mkv"
Aug 11, 2018 02:22:21.693 [6184] DEBUG - [FFMPEG] - Opening 'F:\Videos\Movies\The Danish Girl\The Danish Girl.mkv' for reading
Aug 11, 2018 02:22:21.694 [6184] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Aug 11, 2018 02:22:21.702 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11775&metadataType=4&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.702 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11775&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.714 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=7&metadataItemID=11775&metadataType=4&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.716 [6184] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Aug 11, 2018 02:22:21.721 [6184] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.721 [6184] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Aug 11, 2018 02:22:21.722 [6184] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6763 bytes read:33585 seeks:2 nb_streams:2
Aug 11, 2018 02:22:21.722 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:21.722 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:21.724 [6184] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Aug 11, 2018 02:22:21.724 [6184] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Aug 11, 2018 02:22:21.725 [6184] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Aug 11, 2018 02:22:21.725 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.725 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.725 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.725 [6184] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Aug 11, 2018 02:22:21.725 [6184] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Aug 11, 2018 02:22:21.729 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:21.732 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.732 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.733 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.733 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.736 [6184] DEBUG - [FFMPEG] - no picture 
Aug 11, 2018 02:22:21.739 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.739 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.739 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.739 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.746 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.746 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.746 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.746 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.755 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.755 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.755 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.755 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.763 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.763 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.763 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.763 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.769 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.769 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.769 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.769 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Aug 11, 2018 02:22:21.775 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.776 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.776 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.776 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.779 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.779 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.779 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.779 [6184] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Aug 11, 2018 02:22:21.783 [6184] DEBUG - [FFMPEG] - All info found
Aug 11, 2018 02:22:21.784 [6184] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 58169 bytes read:99121 seeks:2 frames:58
Aug 11, 2018 02:22:21.784 [6184] DEBUG - [MI]  * 3d5f0386eae8d190568af64ad98fa02e1e07a6ed [mkv h264/dca] Duration: 7178004ms, 1920x1040 (1.846154) 23.976fps, bitrate=3607kbps, audio channels=6, optimizedForStreaming=0 ("F:\Videos\Movies\The Danish Girl\The Danish Girl.mkv")
Aug 11, 2018 02:22:21.785 [6184] DEBUG - [MI] Importing 20 media chapters.
Aug 11, 2018 02:22:21.785 [6184] DEBUG - [MI] Chapters had generic titles, clearing.
Aug 11, 2018 02:22:21.786 [6184] DEBUG - [MI] Closing input file: "F:\Videos\Movies\The Danish Girl\The Danish Girl.mkv"
Aug 11, 2018 02:22:21.790 [6184] DEBUG - [FFMPEG] - Statistics: 99121 bytes read, 2 seeks
Aug 11, 2018 02:22:21.791 [6184] DEBUG - Updating media item 335, size=3236447432, metadata_item_id=335
Aug 11, 2018 02:22:21.791 [6184] DEBUG - Updating part with ID=335 [F:\Videos\Movies\The Danish Girl\The Danish Girl.mkv]
Aug 11, 2018 02:22:21.797 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=335&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.810 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=335&metadataType=1&state=-1&mediaState=analyzing
Aug 11, 2018 02:22:21.810 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=335&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.823 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=335&metadataType=1&state=-1&mediaState=thumbnailing
Aug 11, 2018 02:22:21.824 [9520] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=335&metadataType=1&state=-1&mediaState=idle
Aug 11, 2018 02:22:21.842 [9520] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=335&metadataType=1&state=-1&mediaState=idle
