Apr 25, 2019 00:04:40.258 [0x7f9188b7c700] INFO - Plex Media Scanner v1.15.3.876-ad6e39743 - Synology DS718+ x86_64 - build: linux-x86_64 - GMT 01:00
Apr 25, 2019 00:04:40.259 [0x7f9188b7c700] INFO - Linux version: DSM 6.2.1.23824-6, language: en-US
Apr 25, 2019 00:04:40.259 [0x7f9188b7c700] INFO - Processor Intel(R) Celeron(R) CPU J3455 @ 1.50GHz
Apr 25, 2019 00:04:40.259 [0x7f9188b7c700] INFO - /volume1/@appstore/Plex Media Server/Plex Media Scanner --analyze --item 156,151,6,90,119,178,177,77,5,60 --log-file-suffix  Analysis
Apr 25, 2019 00:04:40.220 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Rescanning for external libs: '/volume1/Plex/Library/Application\ Support/Plex\ Media\ Server/Codecs/a22632d-2172-linux-x86_64/'
Apr 25, 2019 00:04:40.220 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libh264_decoder.so
Apr 25, 2019 00:04:40.221 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libhevc_decoder.so
Apr 25, 2019 00:04:40.221 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libdca_decoder.so
Apr 25, 2019 00:04:40.222 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libaac_encoder.so
Apr 25, 2019 00:04:40.222 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/liblibx264_encoder.so
Apr 25, 2019 00:04:40.223 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libmpeg2video_decoder.so
Apr 25, 2019 00:04:40.223 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libac3_decoder.so
Apr 25, 2019 00:04:40.223 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libmp3_decoder.so
Apr 25, 2019 00:04:40.224 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libac3_encoder.so
Apr 25, 2019 00:04:40.224 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libmpeg4_decoder.so
Apr 25, 2019 00:04:40.224 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libaac_lc_decoder.so
Apr 25, 2019 00:04:40.225 [0x7f9191d0c740] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.26.0, threadsafe=1
Apr 25, 2019 00:04:40.532 [0x7f9191d0c740] DEBUG - Analyzing media parts for item 156 (The Iron Lady): 156
Apr 25, 2019 00:04:40.532 [0x7f9191d0c740] DEBUG - [ID 156] Media part analysis: /volume1/video/The Iron Lady - 2011.mkv
Apr 25, 2019 00:04:40.562 [0x7f9191d0c740] DEBUG - [MI] Opening input file: "/volume1/video/The Iron Lady - 2011.mkv"
Apr 25, 2019 00:04:40.562 [0x7f9191d0c740] DEBUG - [FFMPEG] - Opening '/volume1/video/The Iron Lady - 2011.mkv' for reading
Apr 25, 2019 00:04:40.562 [0x7f9191d0c740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 25, 2019 00:04:40.631 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 25, 2019 00:04:40.691 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:40.691 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:40.691 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:40.691 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:40.691 [0x7f9191d0c740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5730 bytes read:133438 seeks:2 nb_streams:4
Apr 25, 2019 00:04:40.692 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:40.692 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:40.694 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:40.694 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:40.694 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 25, 2019 00:04:40.694 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 25, 2019 00:04:40.694 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 25, 2019 00:04:40.694 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Reinit context to 1920x816, pix_fmt: yuv420p
Apr 25, 2019 00:04:40.697 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:40.701 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:40.703 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:40.706 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:40.712 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:40.719 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:40.726 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:40.729 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:40.734 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:40.739 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:40.745 [0x7f9191d0c740] DEBUG - [FFMPEG] - All info found
Apr 25, 2019 00:04:40.745 [0x7f9191d0c740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 144963 bytes read:297278 seeks:2 frames:74
Apr 25, 2019 00:04:40.747 [0x7f9191d0c740] DEBUG - [MI]  * e1ae887b616f445c6eed98847b97dd574299a292 [mkv h264/dca] Duration: 6298368ms, 1920x816 (2.352941) 23.976fps, bitrate=11055kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/The Iron Lady - 2011.mkv")
Apr 25, 2019 00:04:40.747 [0x7f9191d0c740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 25, 2019 00:04:40.748 [0x7f9191d0c740] DEBUG - [MI] Closing input file: "/volume1/video/The Iron Lady - 2011.mkv"
Apr 25, 2019 00:04:40.751 [0x7f9191d0c740] DEBUG - [FFMPEG] - Statistics: 297278 bytes read, 2 seeks
Apr 25, 2019 00:04:40.752 [0x7f9191d0c740] DEBUG - Committing media stream changes for part 156
Apr 25, 2019 00:04:40.844 [0x7f9191d0c740] DEBUG - Updating media item 156, size=8704003806, metadata_item_id=156
Apr 25, 2019 00:04:40.855 [0x7f9191d0c740] DEBUG - Updating part with ID=156 [/volume1/video/The Iron Lady - 2011.mkv]
Apr 25, 2019 00:04:40.923 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:40.925 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:40.926 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:40.930 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:40.932 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:40.936 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=156&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:41.130 [0x7f9191d0c740] DEBUG - It took 10.000000 ms to retrieve 130 items.
Apr 25, 2019 00:04:41.138 [0x7f9191d0c740] DEBUG - Analyzing media parts for item 151 (The Commuter): 151
Apr 25, 2019 00:04:41.138 [0x7f9191d0c740] DEBUG - [ID 151] Media part analysis: /volume1/video/The Commuter - 2018.mkv
Apr 25, 2019 00:04:41.138 [0x7f9191d0c740] DEBUG - [MI] Opening input file: "/volume1/video/The Commuter - 2018.mkv"
Apr 25, 2019 00:04:41.138 [0x7f9191d0c740] DEBUG - [FFMPEG] - Opening '/volume1/video/The Commuter - 2018.mkv' for reading
Apr 25, 2019 00:04:41.138 [0x7f9191d0c740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 25, 2019 00:04:41.216 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 25, 2019 00:04:41.254 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:41.254 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:41.254 [0x7f9191d0c740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6485 bytes read:33603 seeks:2 nb_streams:2
Apr 25, 2019 00:04:41.254 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:41.254 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:41.255 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:41.255 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:41.256 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 25, 2019 00:04:41.256 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 25, 2019 00:04:41.256 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 25, 2019 00:04:41.256 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Reinit context to 1920x816, pix_fmt: yuv420p
Apr 25, 2019 00:04:41.259 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:41.262 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:41.264 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:41.267 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:41.273 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:41.279 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:41.286 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:41.289 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:41.293 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:41.298 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:41.304 [0x7f9191d0c740] DEBUG - [FFMPEG] - All info found
Apr 25, 2019 00:04:41.304 [0x7f9191d0c740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 36824 bytes read:66371 seeks:2 frames:26
Apr 25, 2019 00:04:41.304 [0x7f9191d0c740] DEBUG - [MI]  * 9821ac5557c8be91f33f670d9efdbd8b5db42887 [mkv h264/ac3] Duration: 6295360ms, 1920x808 (2.376238) 23.976fps, bitrate=10433kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/The Commuter - 2018.mkv")
Apr 25, 2019 00:04:41.304 [0x7f9191d0c740] DEBUG - [MI] Importing 16 media chapters.
Apr 25, 2019 00:04:41.305 [0x7f9191d0c740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 25, 2019 00:04:41.306 [0x7f9191d0c740] DEBUG - [MI] Closing input file: "/volume1/video/The Commuter - 2018.mkv"
Apr 25, 2019 00:04:41.307 [0x7f9191d0c740] DEBUG - [FFMPEG] - Statistics: 66371 bytes read, 2 seeks
Apr 25, 2019 00:04:41.308 [0x7f9191d0c740] DEBUG - Committing media stream changes for part 151
Apr 25, 2019 00:04:41.389 [0x7f9191d0c740] DEBUG - Updating media item 151, size=8210535276, metadata_item_id=151
Apr 25, 2019 00:04:41.389 [0x7f9191d0c740] DEBUG - Updating part with ID=151 [/volume1/video/The Commuter - 2018.mkv]
Apr 25, 2019 00:04:41.456 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=151&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:41.457 [0x7f9191d0c740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:41.458 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=151&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:41.458 [0x7f9191d0c740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:41.458 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=151&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:41.462 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=151&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:41.462 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=151&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:41.464 [0x7f9191d0c740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:41.464 [0x7f9191d0c740] DEBUG - Updating metadata item (save) (The Commuter) with ID 151
Apr 25, 2019 00:04:41.467 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=151&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:41.579 [0x7f9191d0c740] VERBOSE - Skipping expensive tags write for 'The Commuter' (type: 9) since nothing changed.
Apr 25, 2019 00:04:41.579 [0x7f9191d0c740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:41.580 [0x7f9191d0c740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:41.582 [0x7f9191d0c740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:41.582 [0x7f9191d0c740] DEBUG - Updating metadata item (save) (The Commuter) with ID 151
Apr 25, 2019 00:04:41.652 [0x7f9191d0c740] DEBUG - It took 10.000000 ms to retrieve 109 items.
Apr 25, 2019 00:04:41.668 [0x7f9191d0c740] DEBUG - Analyzing media parts for item 6 (Abraham Lincoln: Vampire Hunter): 6
Apr 25, 2019 00:04:41.668 [0x7f9191d0c740] DEBUG - [ID 6] Media part analysis: /volume1/video/Abraham Lincoln Vampire Hunter - 2012.mkv
Apr 25, 2019 00:04:41.715 [0x7f9191d0c740] DEBUG - [MI] Opening input file: "/volume1/video/Abraham Lincoln Vampire Hunter - 2012.mkv"
Apr 25, 2019 00:04:41.716 [0x7f9191d0c740] DEBUG - [FFMPEG] - Opening '/volume1/video/Abraham Lincoln Vampire Hunter - 2012.mkv' for reading
Apr 25, 2019 00:04:41.716 [0x7f9191d0c740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 25, 2019 00:04:41.765 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 25, 2019 00:04:41.765 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:41.765 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:41.765 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:41.765 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:41.765 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:41.765 [0x7f9191d0c740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5852 bytes read:32768 seeks:0 nb_streams:5
Apr 25, 2019 00:04:41.765 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:41.765 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:41.766 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:41.766 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:41.767 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 25, 2019 00:04:41.767 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 25, 2019 00:04:41.767 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 25, 2019 00:04:41.767 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Reinit context to 1920x800, pix_fmt: yuv420p
Apr 25, 2019 00:04:41.768 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:41.789 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:41.789 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:41.800 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:41.811 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:41.824 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:41.835 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:41.848 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:41.859 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:41.869 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:41.880 [0x7f9191d0c740] DEBUG - [FFMPEG] - All info found
Apr 25, 2019 00:04:41.880 [0x7f9191d0c740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 351313 bytes read:357764 seeks:0 frames:106
Apr 25, 2019 00:04:41.880 [0x7f9191d0c740] DEBUG - [MI]  * bfbe3003fa89ea184668eb30adc975d3bd7b8e74 [mkv h264/dca] Duration: 6300503ms, 1920x800 (2.400000) 23.976fps, bitrate=10823kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Abraham Lincoln Vampire Hunter - 2012.mkv")
Apr 25, 2019 00:04:41.880 [0x7f9191d0c740] DEBUG - [MI] Importing 24 media chapters.
Apr 25, 2019 00:04:41.880 [0x7f9191d0c740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 25, 2019 00:04:41.881 [0x7f9191d0c740] DEBUG - [MI] Closing input file: "/volume1/video/Abraham Lincoln Vampire Hunter - 2012.mkv"
Apr 25, 2019 00:04:41.882 [0x7f9191d0c740] DEBUG - [FFMPEG] - Statistics: 357764 bytes read, 0 seeks
Apr 25, 2019 00:04:41.884 [0x7f9191d0c740] DEBUG - Committing media stream changes for part 6
Apr 25, 2019 00:04:41.955 [0x7f9191d0c740] DEBUG - Updating media item 6, size=8524252274, metadata_item_id=6
Apr 25, 2019 00:04:41.971 [0x7f9191d0c740] DEBUG - Updating part with ID=6 [/volume1/video/Abraham Lincoln Vampire Hunter - 2012.mkv]
Apr 25, 2019 00:04:42.067 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=6&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:42.068 [0x7f9191d0c740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:42.068 [0x7f9191d0c740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:42.068 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=6&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:42.069 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=6&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:42.072 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=6&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:42.073 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=6&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:42.074 [0x7f9191d0c740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:42.074 [0x7f9191d0c740] DEBUG - Updating metadata item (save) (Abraham Lincoln: Vampire Hunter) with ID 6
Apr 25, 2019 00:04:42.077 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=6&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:42.135 [0x7f9191d0c740] VERBOSE - Skipping expensive tags write for 'Abraham Lincoln: Vampire Hunter' (type: 9) since nothing changed.
Apr 25, 2019 00:04:42.136 [0x7f9191d0c740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:42.136 [0x7f9191d0c740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:42.138 [0x7f9191d0c740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:42.138 [0x7f9191d0c740] DEBUG - Updating metadata item (save) (Abraham Lincoln: Vampire Hunter) with ID 6
Apr 25, 2019 00:04:42.270 [0x7f9191d0c740] DEBUG - Analyzing media parts for item 90 (Margin Call): 90
Apr 25, 2019 00:04:42.270 [0x7f9191d0c740] DEBUG - [ID 90] Media part analysis: /volume1/video/Margin Call - 2011.mkv
Apr 25, 2019 00:04:42.301 [0x7f9191d0c740] DEBUG - [MI] Opening input file: "/volume1/video/Margin Call - 2011.mkv"
Apr 25, 2019 00:04:42.301 [0x7f9191d0c740] DEBUG - [FFMPEG] - Opening '/volume1/video/Margin Call - 2011.mkv' for reading
Apr 25, 2019 00:04:42.301 [0x7f9191d0c740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 25, 2019 00:04:42.343 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 25, 2019 00:04:42.343 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:42.343 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:42.343 [0x7f9191d0c740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5638 bytes read:32768 seeks:0 nb_streams:2
Apr 25, 2019 00:04:42.343 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:42.343 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:42.344 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:42.344 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:42.344 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 25, 2019 00:04:42.344 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 25, 2019 00:04:42.344 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 25, 2019 00:04:42.344 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Reinit context to 1920x1088, pix_fmt: yuv420p
Apr 25, 2019 00:04:42.346 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:42.350 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:42.351 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:42.355 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:42.362 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:42.370 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:42.379 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:42.383 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:42.389 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:42.395 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:42.401 [0x7f9191d0c740] DEBUG - [FFMPEG] - All info found
Apr 25, 2019 00:04:42.401 [0x7f9191d0c740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 56500 bytes read:65536 seeks:0 frames:58
Apr 25, 2019 00:04:42.401 [0x7f9191d0c740] DEBUG - [MI]  * 1bf6042beed82324c50585f8c905d13131b5d0fb [mkv h264/dca] Duration: 6390843ms, 1920x1080 (1.777778) 23.976fps, bitrate=8812kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Margin Call - 2011.mkv")
Apr 25, 2019 00:04:42.401 [0x7f9191d0c740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 25, 2019 00:04:42.402 [0x7f9191d0c740] DEBUG - [MI] Closing input file: "/volume1/video/Margin Call - 2011.mkv"
Apr 25, 2019 00:04:42.403 [0x7f9191d0c740] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Apr 25, 2019 00:04:42.405 [0x7f9191d0c740] DEBUG - Committing media stream changes for part 90
Apr 25, 2019 00:04:42.489 [0x7f9191d0c740] DEBUG - Updating media item 90, size=7039772207, metadata_item_id=90
Apr 25, 2019 00:04:42.489 [0x7f9191d0c740] DEBUG - Updating part with ID=90 [/volume1/video/Margin Call - 2011.mkv]
Apr 25, 2019 00:04:42.584 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=90&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:42.586 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=90&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:42.586 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=90&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:42.589 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=90&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:42.590 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=90&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:42.592 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=90&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:42.613 [0x7f9191d0c740] DEBUG - Analyzing media parts for item 119 (Safe House): 119
Apr 25, 2019 00:04:42.613 [0x7f9191d0c740] DEBUG - [ID 119] Media part analysis: /volume1/video/Safe House - 2012.mkv
Apr 25, 2019 00:04:42.642 [0x7f9191d0c740] DEBUG - [MI] Opening input file: "/volume1/video/Safe House - 2012.mkv"
Apr 25, 2019 00:04:42.642 [0x7f9191d0c740] DEBUG - [FFMPEG] - Opening '/volume1/video/Safe House - 2012.mkv' for reading
Apr 25, 2019 00:04:42.642 [0x7f9191d0c740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 25, 2019 00:04:42.669 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 25, 2019 00:04:42.669 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:42.669 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:42.669 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:42.670 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:42.670 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:42.670 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:5 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:42.670 [0x7f9191d0c740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6879 bytes read:32768 seeks:0 nb_streams:6
Apr 25, 2019 00:04:42.670 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:42.670 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:42.671 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:42.671 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:42.671 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 25, 2019 00:04:42.671 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 25, 2019 00:04:42.671 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 25, 2019 00:04:42.671 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Reinit context to 1280x544, pix_fmt: yuv420p
Apr 25, 2019 00:04:42.672 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:42.676 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:42.676 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:42.678 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:42.681 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:42.684 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:42.687 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:42.689 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:42.692 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:42.696 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:42.699 [0x7f9191d0c740] DEBUG - [FFMPEG] - All info found
Apr 25, 2019 00:04:42.699 [0x7f9191d0c740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 59596 bytes read:65536 seeks:0 frames:26
Apr 25, 2019 00:04:42.699 [0x7f9191d0c740] DEBUG - [MI]  * 10677d7a0ea75d72701d8eec6a9f77cbbeff73b2 [mkv h264/ac3] Duration: 6894560ms, 1280x534 (2.397004) 23.976fps, bitrate=12714kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Safe House - 2012.mkv")
Apr 25, 2019 00:04:42.699 [0x7f9191d0c740] DEBUG - [MI] Importing 20 media chapters.
Apr 25, 2019 00:04:42.700 [0x7f9191d0c740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 25, 2019 00:04:42.701 [0x7f9191d0c740] DEBUG - [MI] Closing input file: "/volume1/video/Safe House - 2012.mkv"
Apr 25, 2019 00:04:42.701 [0x7f9191d0c740] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Apr 25, 2019 00:04:42.703 [0x7f9191d0c740] DEBUG - Committing media stream changes for part 119
Apr 25, 2019 00:04:42.800 [0x7f9191d0c740] DEBUG - Updating media item 119, size=10957708949, metadata_item_id=119
Apr 25, 2019 00:04:42.800 [0x7f9191d0c740] DEBUG - Updating part with ID=119 [/volume1/video/Safe House - 2012.mkv]
Apr 25, 2019 00:04:42.932 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=119&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:42.934 [0x7f9191d0c740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:42.934 [0x7f9191d0c740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:42.935 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=119&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:42.935 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=119&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:42.938 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=119&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:42.938 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=119&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:42.940 [0x7f9191d0c740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:42.941 [0x7f9191d0c740] DEBUG - Updating metadata item (save) (Safe House) with ID 119
Apr 25, 2019 00:04:42.942 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=119&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:43.035 [0x7f9191d0c740] VERBOSE - Skipping expensive tags write for 'Safe House' (type: 9) since nothing changed.
Apr 25, 2019 00:04:43.035 [0x7f9191d0c740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:43.035 [0x7f9191d0c740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:43.038 [0x7f9191d0c740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:43.038 [0x7f9191d0c740] DEBUG - Updating metadata item (save) (Safe House) with ID 119
Apr 25, 2019 00:04:43.404 [0x7f9191d0c740] DEBUG - It took 0.000000 ms to retrieve 104 items.
Apr 25, 2019 00:04:43.418 [0x7f9191d0c740] DEBUG - Analyzing media parts for item 178 (Transformers: Revenge of the Fallen): 178
Apr 25, 2019 00:04:43.418 [0x7f9191d0c740] DEBUG - [ID 178] Media part analysis: /volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv
Apr 25, 2019 00:04:43.445 [0x7f9191d0c740] DEBUG - [MI] Opening input file: "/volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv"
Apr 25, 2019 00:04:43.445 [0x7f9191d0c740] DEBUG - [FFMPEG] - Opening '/volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv' for reading
Apr 25, 2019 00:04:43.445 [0x7f9191d0c740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 25, 2019 00:04:43.472 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 25, 2019 00:04:43.479 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:43.479 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:43.479 [0x7f9191d0c740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5719 bytes read:145018 seeks:2 nb_streams:2
Apr 25, 2019 00:04:43.479 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:43.479 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:43.480 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:43.480 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:43.480 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 25, 2019 00:04:43.480 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 25, 2019 00:04:43.480 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 25, 2019 00:04:43.480 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Reinit context to 1280x720, pix_fmt: yuv420p
Apr 25, 2019 00:04:43.481 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:43.488 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:43.492 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:43.497 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:43.501 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:43.506 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:43.511 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:43.518 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:43.523 [0x7f9191d0c740] DEBUG - [FFMPEG] - All info found
Apr 25, 2019 00:04:43.523 [0x7f9191d0c740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 107440 bytes read:276090 seeks:2 frames:41
Apr 25, 2019 00:04:43.523 [0x7f9191d0c740] DEBUG - [MI]  * 1c5e613ccb88daed419d794ca64063e9352448a5 [mkv h264/dca] Duration: 9024939ms, 1280x720 (1.777778) 23.976fps, bitrate=8291kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv")
Apr 25, 2019 00:04:43.523 [0x7f9191d0c740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 25, 2019 00:04:43.524 [0x7f9191d0c740] DEBUG - [MI] Closing input file: "/volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv"
Apr 25, 2019 00:04:43.524 [0x7f9191d0c740] DEBUG - [FFMPEG] - Statistics: 276090 bytes read, 2 seeks
Apr 25, 2019 00:04:43.525 [0x7f9191d0c740] DEBUG - Committing media stream changes for part 178
Apr 25, 2019 00:04:43.600 [0x7f9191d0c740] DEBUG - Updating media item 178, size=9353710165, metadata_item_id=178
Apr 25, 2019 00:04:43.600 [0x7f9191d0c740] DEBUG - Updating part with ID=178 [/volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv]
Apr 25, 2019 00:04:43.678 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=178&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:43.680 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=178&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:43.681 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=178&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:43.684 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=178&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:43.684 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=178&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:43.687 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=178&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:43.701 [0x7f9191d0c740] DEBUG - It took 10.000000 ms to retrieve 109 items.
Apr 25, 2019 00:04:43.734 [0x7f9191d0c740] DEBUG - Analyzing media parts for item 177 (Transformers: Dark of the Moon): 177
Apr 25, 2019 00:04:43.735 [0x7f9191d0c740] DEBUG - [ID 177] Media part analysis: /volume1/video/Transformers - Dark Of The Moon - 2011.mkv
Apr 25, 2019 00:04:43.766 [0x7f9191d0c740] DEBUG - [MI] Opening input file: "/volume1/video/Transformers - Dark Of The Moon - 2011.mkv"
Apr 25, 2019 00:04:43.766 [0x7f9191d0c740] DEBUG - [FFMPEG] - Opening '/volume1/video/Transformers - Dark Of The Moon - 2011.mkv' for reading
Apr 25, 2019 00:04:43.766 [0x7f9191d0c740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 25, 2019 00:04:43.797 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 25, 2019 00:04:43.800 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:43.800 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:43.800 [0x7f9191d0c740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5749 bytes read:156764 seeks:2 nb_streams:2
Apr 25, 2019 00:04:43.801 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:43.801 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:43.801 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:43.801 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:43.801 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 25, 2019 00:04:43.802 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 25, 2019 00:04:43.802 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 25, 2019 00:04:43.802 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Reinit context to 1280x544, pix_fmt: yuv420p
Apr 25, 2019 00:04:43.802 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:43.804 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:43.804 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:43.806 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:43.808 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:43.810 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:43.813 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:43.815 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:43.817 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:43.819 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:43.822 [0x7f9191d0c740] DEBUG - [FFMPEG] - All info found
Apr 25, 2019 00:04:43.822 [0x7f9191d0c740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 87651 bytes read:255068 seeks:2 frames:50
Apr 25, 2019 00:04:43.822 [0x7f9191d0c740] DEBUG - [MI]  * 7251db3d3de1af62b41b6dcbf96bd52e83be437a [mkv h264/dca] Duration: 9263286ms, 1280x534 (2.397004) 23.976fps, bitrate=8818kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Transformers - Dark Of The Moon - 2011.mkv")
Apr 25, 2019 00:04:43.823 [0x7f9191d0c740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 25, 2019 00:04:43.823 [0x7f9191d0c740] DEBUG - [MI] Closing input file: "/volume1/video/Transformers - Dark Of The Moon - 2011.mkv"
Apr 25, 2019 00:04:43.824 [0x7f9191d0c740] DEBUG - [FFMPEG] - Statistics: 255068 bytes read, 2 seeks
Apr 25, 2019 00:04:43.825 [0x7f9191d0c740] DEBUG - Committing media stream changes for part 177
Apr 25, 2019 00:04:43.911 [0x7f9191d0c740] DEBUG - Updating media item 177, size=10210583089, metadata_item_id=177
Apr 25, 2019 00:04:43.926 [0x7f9191d0c740] DEBUG - Updating part with ID=177 [/volume1/video/Transformers - Dark Of The Moon - 2011.mkv]
Apr 25, 2019 00:04:44.011 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:44.013 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:44.014 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:44.017 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:44.017 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:44.020 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=177&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:44.031 [0x7f9191d0c740] DEBUG - It took 10.000000 ms to retrieve 155 items.
Apr 25, 2019 00:04:44.036 [0x7f9191d0c740] DEBUG - Analyzing media parts for item 77 (The Lord of the Rings: The Two Towers): 77
Apr 25, 2019 00:04:44.036 [0x7f9191d0c740] DEBUG - [ID 77] Media part analysis: /volume1/video/Lord of the Rings - Two Towers - 2002.mkv
Apr 25, 2019 00:04:44.074 [0x7f9191d0c740] DEBUG - [MI] Opening input file: "/volume1/video/Lord of the Rings - Two Towers - 2002.mkv"
Apr 25, 2019 00:04:44.074 [0x7f9191d0c740] DEBUG - [FFMPEG] - Opening '/volume1/video/Lord of the Rings - Two Towers - 2002.mkv' for reading
Apr 25, 2019 00:04:44.074 [0x7f9191d0c740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 25, 2019 00:04:44.108 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 25, 2019 00:04:44.108 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:44.108 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:44.108 [0x7f9191d0c740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5795 bytes read:32768 seeks:0 nb_streams:2
Apr 25, 2019 00:04:44.108 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:44.108 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:44.109 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:44.109 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:44.109 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 25, 2019 00:04:44.109 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 25, 2019 00:04:44.109 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 25, 2019 00:04:44.109 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Reinit context to 1280x544, pix_fmt: yuv420p
Apr 25, 2019 00:04:44.110 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:44.111 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.112 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:44.113 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.116 [0x7f9191d0c740] DEBUG - [FFMPEG] - Non-increasing DTS in stream 1: packet 7 with DTS 85, packet 8 with DTS 85
Apr 25, 2019 00:04:44.116 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.118 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.120 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.122 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.124 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.127 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.129 [0x7f9191d0c740] DEBUG - [FFMPEG] - All info found
Apr 25, 2019 00:04:44.129 [0x7f9191d0c740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 87664 bytes read:98304 seeks:0 frames:50
Apr 25, 2019 00:04:44.129 [0x7f9191d0c740] DEBUG - [MI]  * 8f4555497bf461c4a08970a5651e1ca6fbf79194 [mkv h264/dca] Duration: 14131499ms, 1280x536 (2.388060) 23.976fps, bitrate=8010kbps, audio channels=7, optimizedForStreaming=0 ("/volume1/video/Lord of the Rings - Two Towers - 2002.mkv")
Apr 25, 2019 00:04:44.129 [0x7f9191d0c740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 25, 2019 00:04:44.130 [0x7f9191d0c740] DEBUG - [MI] Closing input file: "/volume1/video/Lord of the Rings - Two Towers - 2002.mkv"
Apr 25, 2019 00:04:44.131 [0x7f9191d0c740] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 0 seeks
Apr 25, 2019 00:04:44.132 [0x7f9191d0c740] DEBUG - Updating media item 77, size=14149727970, metadata_item_id=77
Apr 25, 2019 00:04:44.133 [0x7f9191d0c740] DEBUG - Updating part with ID=77 [/volume1/video/Lord of the Rings - Two Towers - 2002.mkv]
Apr 25, 2019 00:04:44.211 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:44.214 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:44.214 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:44.218 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:44.218 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:44.220 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:44.225 [0x7f9191d0c740] DEBUG - Analyzing media parts for item 5 (A Quiet Place): 5
Apr 25, 2019 00:04:44.225 [0x7f9191d0c740] DEBUG - [ID 5] Media part analysis: /volume1/video/A Quiet Place - 2018.mkv
Apr 25, 2019 00:04:44.225 [0x7f9191d0c740] DEBUG - [MI] Opening input file: "/volume1/video/A Quiet Place - 2018.mkv"
Apr 25, 2019 00:04:44.225 [0x7f9191d0c740] DEBUG - [FFMPEG] - Opening '/volume1/video/A Quiet Place - 2018.mkv' for reading
Apr 25, 2019 00:04:44.225 [0x7f9191d0c740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 25, 2019 00:04:44.279 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 25, 2019 00:04:44.337 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:44.337 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:44.337 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:44.337 [0x7f9191d0c740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6539 bytes read:33933 seeks:2 nb_streams:3
Apr 25, 2019 00:04:44.338 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:44.338 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:44.339 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:44.339 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:44.339 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:44.339 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:44.339 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 25, 2019 00:04:44.339 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 25, 2019 00:04:44.339 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 25, 2019 00:04:44.339 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Reinit context to 1920x800, pix_fmt: yuv420p
Apr 25, 2019 00:04:44.341 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:44.344 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.345 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:44.347 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.352 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:44.356 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:44.363 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.366 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.370 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:44.374 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:44.380 [0x7f9191d0c740] DEBUG - [FFMPEG] - All info found
Apr 25, 2019 00:04:44.380 [0x7f9191d0c740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 49265 bytes read:99469 seeks:2 frames:26
Apr 25, 2019 00:04:44.380 [0x7f9191d0c740] DEBUG - [MI]  * f25ec75b2e4705bb3f2caadece3b5c1bdfcf04d1 [mkv h264/ac3] Duration: 5414912ms, 1920x800 (2.400000) 23.976fps, bitrate=10396kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/A Quiet Place - 2018.mkv")
Apr 25, 2019 00:04:44.380 [0x7f9191d0c740] DEBUG - [MI] Importing 15 media chapters.
Apr 25, 2019 00:04:44.381 [0x7f9191d0c740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 25, 2019 00:04:44.381 [0x7f9191d0c740] DEBUG - [MI] Closing input file: "/volume1/video/A Quiet Place - 2018.mkv"
Apr 25, 2019 00:04:44.382 [0x7f9191d0c740] DEBUG - [FFMPEG] - Statistics: 99469 bytes read, 2 seeks
Apr 25, 2019 00:04:44.384 [0x7f9191d0c740] DEBUG - Committing media stream changes for part 5
Apr 25, 2019 00:04:44.455 [0x7f9191d0c740] DEBUG - Updating media item 5, size=7037059189, metadata_item_id=5
Apr 25, 2019 00:04:44.470 [0x7f9191d0c740] DEBUG - Updating part with ID=5 [/volume1/video/A Quiet Place - 2018.mkv]
Apr 25, 2019 00:04:44.545 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:44.546 [0x7f9191d0c740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:44.546 [0x7f9191d0c740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:44.547 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:44.547 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:44.548 [0x7f9191d0c740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:44.549 [0x7f9191d0c740] DEBUG - Updating metadata item (save) (A Quiet Place) with ID 5
Apr 25, 2019 00:04:44.549 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:44.550 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:44.554 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=5&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:44.623 [0x7f9191d0c740] VERBOSE - Skipping expensive tags write for 'A Quiet Place' (type: 9) since nothing changed.
Apr 25, 2019 00:04:44.623 [0x7f9191d0c740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:44.624 [0x7f9191d0c740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:44.626 [0x7f9191d0c740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:44.626 [0x7f9191d0c740] DEBUG - Updating metadata item (save) (A Quiet Place) with ID 5
Apr 25, 2019 00:04:44.710 [0x7f9191d0c740] DEBUG - Analyzing media parts for item 60 (Insidious): 60
Apr 25, 2019 00:04:44.711 [0x7f9191d0c740] DEBUG - [ID 60] Media part analysis: /volume1/video/Insidious - 2011.mkv
Apr 25, 2019 00:04:44.711 [0x7f9191d0c740] DEBUG - [MI] Opening input file: "/volume1/video/Insidious - 2011.mkv"
Apr 25, 2019 00:04:44.711 [0x7f9191d0c740] DEBUG - [FFMPEG] - Opening '/volume1/video/Insidious - 2011.mkv' for reading
Apr 25, 2019 00:04:44.711 [0x7f9191d0c740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 25, 2019 00:04:44.727 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 25, 2019 00:04:44.727 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:44.727 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:44.727 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:44.727 [0x7f9191d0c740] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Apr 25, 2019 00:04:44.728 [0x7f9191d0c740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7001 bytes read:32768 seeks:0 nb_streams:4
Apr 25, 2019 00:04:44.728 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:44.728 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] - palette:
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0x000000
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0xf0f0f0
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0xcccccc
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0x999999
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0x3333fa
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0x1111bb
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0xfa3333
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0xbb1111
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0x33fa33
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0x11bb11
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0xfafa33
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0xbbbb11
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0xfa33fa
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0xbb11bb
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0x33fafa
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] -  0x11bbbb
Apr 25, 2019 00:04:44.729 [0x7f9191d0c740] DEBUG - [FFMPEG] - 
Apr 25, 2019 00:04:44.731 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 25, 2019 00:04:44.731 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 25, 2019 00:04:44.731 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 25, 2019 00:04:44.731 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 25, 2019 00:04:44.732 [0x7f9191d0c740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 25, 2019 00:04:44.732 [0x7f9191d0c740] VERBOSE - [FFMPEG] - Reinit context to 1920x800, pix_fmt: yuv420p
Apr 25, 2019 00:04:44.733 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:44.777 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.778 [0x7f9191d0c740] DEBUG - [FFMPEG] - no picture 
Apr 25, 2019 00:04:44.781 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.821 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.827 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:44.831 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:44.843 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 25, 2019 00:04:44.852 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.855 [0x7f9191d0c740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 25, 2019 00:04:44.860 [0x7f9191d0c740] DEBUG - [FFMPEG] - All info found
Apr 25, 2019 00:04:44.860 [0x7f9191d0c740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 587078 bytes read:611943 seeks:0 frames:26
Apr 25, 2019 00:04:44.861 [0x7f9191d0c740] DEBUG - [MI]  * b33a0aadd1ad6b1b6a5aadffe8f5266add1e7bdf [mkv h264/ac3] Duration: 6172192ms, 1920x800 (2.400000) 23.976fps, bitrate=13917kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Insidious - 2011.mkv")
Apr 25, 2019 00:04:44.861 [0x7f9191d0c740] DEBUG - [MI] Importing 16 media chapters.
Apr 25, 2019 00:04:44.861 [0x7f9191d0c740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 25, 2019 00:04:44.862 [0x7f9191d0c740] DEBUG - [MI] Closing input file: "/volume1/video/Insidious - 2011.mkv"
Apr 25, 2019 00:04:44.863 [0x7f9191d0c740] DEBUG - [FFMPEG] - Statistics: 611943 bytes read, 0 seeks
Apr 25, 2019 00:04:44.864 [0x7f9191d0c740] DEBUG - Committing media stream changes for part 60
Apr 25, 2019 00:04:44.944 [0x7f9191d0c740] DEBUG - Updating media item 60, size=10737386985, metadata_item_id=60
Apr 25, 2019 00:04:44.945 [0x7f9191d0c740] DEBUG - Updating part with ID=60 [/volume1/video/Insidious - 2011.mkv]
Apr 25, 2019 00:04:45.022 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:45.024 [0x7f9191d0c740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:45.024 [0x7f9191d0c740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:45.024 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&mediaState=analyzing
Apr 25, 2019 00:04:45.025 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:45.027 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&mediaState=thumbnailing
Apr 25, 2019 00:04:45.027 [0x7f9182268700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:45.029 [0x7f9191d0c740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:45.029 [0x7f9191d0c740] DEBUG - Updating metadata item (save) (Insidious) with ID 60
Apr 25, 2019 00:04:45.031 [0x7f9182268700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&mediaState=idle
Apr 25, 2019 00:04:45.112 [0x7f9191d0c740] VERBOSE - Skipping expensive tags write for 'Insidious' (type: 9) since nothing changed.
Apr 25, 2019 00:04:45.112 [0x7f9191d0c740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:45.113 [0x7f9191d0c740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:45.115 [0x7f9191d0c740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 25, 2019 00:04:45.115 [0x7f9191d0c740] DEBUG - Updating metadata item (save) (Insidious) with ID 60
