Apr 21, 2019 00:08:21.952 [0x7fd669538700] INFO - Plex Media Scanner v1.15.3.876-ad6e39743 - Synology DS718+ x86_64 - build: linux-x86_64 - GMT 01:00
Apr 21, 2019 00:08:21.952 [0x7fd669538700] INFO - Linux version: DSM 6.2.1.23824-6, language: en-US
Apr 21, 2019 00:08:21.952 [0x7fd669538700] INFO - Processor Intel(R) Celeron(R) CPU J3455 @ 1.50GHz
Apr 21, 2019 00:08:21.952 [0x7fd669538700] INFO - /volume1/@appstore/Plex Media Server/Plex Media Scanner --analyze --item 135,2215,3621,178,180,1185,17482,91,2640,3426 --log-file-suffix  Analysis
Apr 21, 2019 00:08:21.952 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Rescanning for external libs: '/volume1/Plex/Library/Application\ Support/Plex\ Media\ Server/Codecs/a22632d-2172-linux-x86_64/'
Apr 21, 2019 00:08:21.952 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libh264_decoder.so
Apr 21, 2019 00:08:21.953 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libhevc_decoder.so
Apr 21, 2019 00:08:21.953 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libdca_decoder.so
Apr 21, 2019 00:08:21.954 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libaac_encoder.so
Apr 21, 2019 00:08:21.954 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/liblibx264_encoder.so
Apr 21, 2019 00:08:21.955 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libmpeg2video_decoder.so
Apr 21, 2019 00:08:21.955 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libac3_decoder.so
Apr 21, 2019 00:08:21.956 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libmp3_decoder.so
Apr 21, 2019 00:08:21.956 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libac3_encoder.so
Apr 21, 2019 00:08:21.956 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libmpeg4_decoder.so
Apr 21, 2019 00:08:21.957 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Loading external lib /volume1/Plex/Library/Application Support/Plex Media Server/Codecs/a22632d-2172-linux-x86_64/libaac_lc_decoder.so
Apr 21, 2019 00:08:21.957 [0x7fd6726c8740] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.26.0, threadsafe=1
Apr 21, 2019 00:08:22.136 [0x7fd6726c8740] DEBUG - It took 10.000000 ms to retrieve 140 items.
Apr 21, 2019 00:08:22.143 [0x7fd6726c8740] DEBUG - Analyzing media parts for item 135 (Star Wars: Episode II - Attack of the Clones): 10071
Apr 21, 2019 00:08:22.144 [0x7fd6726c8740] DEBUG - [ID 10083] Media part analysis: /volume1/video/Star Wars - Episode II - Attack Of The Clones - 2002.mkv
Apr 21, 2019 00:08:22.144 [0x7fd6726c8740] DEBUG - [MI] Opening input file: "/volume1/video/Star Wars - Episode II - Attack Of The Clones - 2002.mkv"
Apr 21, 2019 00:08:22.144 [0x7fd6726c8740] DEBUG - [FFMPEG] - Opening '/volume1/video/Star Wars - Episode II - Attack Of The Clones - 2002.mkv' for reading
Apr 21, 2019 00:08:22.144 [0x7fd6726c8740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 21, 2019 00:08:22.187 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 21, 2019 00:08:22.214 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.214 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.214 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.214 [0x7fd6726c8740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1296 bytes read:65536 seeks:2 nb_streams:3
Apr 21, 2019 00:08:22.215 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:22.215 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:22.218 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:22.218 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:22.218 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.219 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 21, 2019 00:08:22.219 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 21, 2019 00:08:22.219 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Reinit context to 1920x816, pix_fmt: yuv420p
Apr 21, 2019 00:08:22.222 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:22.257 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:22.259 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:22.262 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:22.269 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.276 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.283 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:22.286 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:22.291 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.295 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.300 [0x7fd6726c8740] DEBUG - [FFMPEG] - All info found
Apr 21, 2019 00:08:22.300 [0x7fd6726c8740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 274366 bytes read:359064 seeks:2 frames:20
Apr 21, 2019 00:08:22.302 [0x7fd6726c8740] DEBUG - [MI]  * c4a299ec784b1e52535ddae9526eba8c3a7d8687 [mkv h264/ac3] Duration: 8547878ms, 1920x816 (2.352941) 23.976fps, bitrate=8591kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Star Wars - Episode II - Attack Of The Clones - 2002.mkv")
Apr 21, 2019 00:08:22.302 [0x7fd6726c8740] DEBUG - [MI] Importing 50 media chapters.
Apr 21, 2019 00:08:22.303 [0x7fd6726c8740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 21, 2019 00:08:22.304 [0x7fd6726c8740] DEBUG - [MI] Closing input file: "/volume1/video/Star Wars - Episode II - Attack Of The Clones - 2002.mkv"
Apr 21, 2019 00:08:22.306 [0x7fd6726c8740] DEBUG - [FFMPEG] - Statistics: 359064 bytes read, 2 seeks
Apr 21, 2019 00:08:22.309 [0x7fd6726c8740] DEBUG - Committing media stream changes for part 10083
Apr 21, 2019 00:08:22.379 [0x7fd6726c8740] DEBUG - Updating media item 10071, size=9179667388, metadata_item_id=135
Apr 21, 2019 00:08:22.379 [0x7fd6726c8740] DEBUG - Updating part with ID=10083 [/volume1/video/Star Wars - Episode II - Attack Of The Clones - 2002.mkv]
Apr 21, 2019 00:08:22.446 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=135&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:22.449 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=135&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:22.449 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=135&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:22.450 [0x7fd6726c8740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 21, 2019 00:08:22.450 [0x7fd6726c8740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 21, 2019 00:08:22.452 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=135&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:22.452 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=135&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:22.454 [0x7fd6726c8740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 21, 2019 00:08:22.454 [0x7fd6726c8740] DEBUG - Updating metadata item (save) (Star Wars: Episode II - Attack of the Clones) with ID 135
Apr 21, 2019 00:08:22.454 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=135&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:22.527 [0x7fd6726c8740] VERBOSE - Skipping expensive tags write for 'Star Wars: Episode II - Attack of the Clones' (type: 9) since nothing changed.
Apr 21, 2019 00:08:22.541 [0x7fd6726c8740] DEBUG - Analyzing media parts for item 2215 (Suburbicon): 4090
Apr 21, 2019 00:08:22.541 [0x7fd6726c8740] DEBUG - [ID 4090] Media part analysis: /volume1/video/Suburbicon - 2017.mkv
Apr 21, 2019 00:08:22.568 [0x7fd6726c8740] DEBUG - [MI] Opening input file: "/volume1/video/Suburbicon - 2017.mkv"
Apr 21, 2019 00:08:22.569 [0x7fd6726c8740] DEBUG - [FFMPEG] - Opening '/volume1/video/Suburbicon - 2017.mkv' for reading
Apr 21, 2019 00:08:22.569 [0x7fd6726c8740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 21, 2019 00:08:22.620 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 21, 2019 00:08:22.620 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.620 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.620 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.620 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.620 [0x7fd6726c8740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6608 bytes read:32768 seeks:0 nb_streams:4
Apr 21, 2019 00:08:22.620 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:22.621 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:22.621 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:22.621 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:22.622 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.622 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 21, 2019 00:08:22.622 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 21, 2019 00:08:22.622 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Reinit context to 1920x816, pix_fmt: yuv420p
Apr 21, 2019 00:08:22.625 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:22.629 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:22.631 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:22.633 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:22.639 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.645 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.651 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:22.654 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:22.658 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.662 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.669 [0x7fd6726c8740] DEBUG - [FFMPEG] - All info found
Apr 21, 2019 00:08:22.669 [0x7fd6726c8740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 104883 bytes read:131072 seeks:0 frames:58
Apr 21, 2019 00:08:22.669 [0x7fd6726c8740] DEBUG - [MI]  * fa178548b5806ac3147c3c578b5ce14b740c5cb9 [mkv h264/dca] Duration: 6302592ms, 1920x808 (2.376238) 23.976fps, bitrate=10820kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Suburbicon - 2017.mkv")
Apr 21, 2019 00:08:22.669 [0x7fd6726c8740] DEBUG - [MI] Importing 15 media chapters.
Apr 21, 2019 00:08:22.670 [0x7fd6726c8740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 21, 2019 00:08:22.670 [0x7fd6726c8740] DEBUG - [MI] Closing input file: "/volume1/video/Suburbicon - 2017.mkv"
Apr 21, 2019 00:08:22.672 [0x7fd6726c8740] DEBUG - [FFMPEG] - Statistics: 131072 bytes read, 0 seeks
Apr 21, 2019 00:08:22.673 [0x7fd6726c8740] DEBUG - Committing media stream changes for part 4090
Apr 21, 2019 00:08:22.746 [0x7fd6726c8740] DEBUG - Updating media item 4090, size=8524708353, metadata_item_id=2215
Apr 21, 2019 00:08:22.746 [0x7fd6726c8740] DEBUG - Updating part with ID=4090 [/volume1/video/Suburbicon - 2017.mkv]
Apr 21, 2019 00:08:22.836 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2215&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:22.838 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2215&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:22.839 [0x7fd6726c8740] VERBOSE - Skipping expensive tags write for 'Suburbicon' (type: 9) since nothing changed.
Apr 21, 2019 00:08:22.839 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2215&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:22.841 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2215&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:22.841 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2215&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:22.844 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2215&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:22.852 [0x7fd6726c8740] DEBUG - Analyzing media parts for item 3621 (The Founder): 7807
Apr 21, 2019 00:08:22.852 [0x7fd6726c8740] DEBUG - [ID 7807] Media part analysis: /volume1/video/The Founder - 2016.mkv
Apr 21, 2019 00:08:22.856 [0x7fd6726c8740] DEBUG - [MI] Opening input file: "/volume1/video/The Founder - 2016.mkv"
Apr 21, 2019 00:08:22.856 [0x7fd6726c8740] DEBUG - [FFMPEG] - Opening '/volume1/video/The Founder - 2016.mkv' for reading
Apr 21, 2019 00:08:22.856 [0x7fd6726c8740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 21, 2019 00:08:22.896 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 21, 2019 00:08:22.957 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.957 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.957 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.957 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.957 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.958 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:5 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.958 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:6 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.958 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:7 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:22.958 [0x7fd6726c8740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7121 bytes read:36071 seeks:2 nb_streams:8
Apr 21, 2019 00:08:22.958 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:22.958 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:22.959 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:22.959 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:22.961 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:22.961 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:22.961 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.961 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.961 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.961 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 21, 2019 00:08:22.961 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 21, 2019 00:08:22.961 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Reinit context to 1920x800, pix_fmt: yuv420p
Apr 21, 2019 00:08:22.964 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:22.968 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.968 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:22.972 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.972 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.979 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.979 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.984 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.984 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.990 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.990 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:22.993 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.993 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:22.998 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:22.998 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.003 [0x7fd6726c8740] DEBUG - [FFMPEG] - All info found
Apr 21, 2019 00:08:23.003 [0x7fd6726c8740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 178720 bytes read:232679 seeks:2 frames:89
Apr 21, 2019 00:08:23.004 [0x7fd6726c8740] DEBUG - [MI]  * 5207c2b43418215608f735d584254b73b55bb5b3 [mkv h264/dca] Duration: 6949483ms, 1920x800 (2.400000) 24.000fps, bitrate=11861kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/The Founder - 2016.mkv")
Apr 21, 2019 00:08:23.004 [0x7fd6726c8740] DEBUG - [MI] Importing 16 media chapters.
Apr 21, 2019 00:08:23.004 [0x7fd6726c8740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 21, 2019 00:08:23.005 [0x7fd6726c8740] DEBUG - [MI] Closing input file: "/volume1/video/The Founder - 2016.mkv"
Apr 21, 2019 00:08:23.005 [0x7fd6726c8740] DEBUG - [FFMPEG] - Statistics: 232679 bytes read, 2 seeks
Apr 21, 2019 00:08:23.008 [0x7fd6726c8740] DEBUG - Updating media item 7807, size=10303536041, metadata_item_id=3621
Apr 21, 2019 00:08:23.008 [0x7fd6726c8740] DEBUG - Updating part with ID=7807 [/volume1/video/The Founder - 2016.mkv]
Apr 21, 2019 00:08:23.057 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3621&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:23.059 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3621&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:23.059 [0x7fd6726c8740] VERBOSE - Skipping expensive tags write for 'The Founder' (type: 9) since nothing changed.
Apr 21, 2019 00:08:23.060 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3621&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:23.062 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3621&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:23.062 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3621&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:23.065 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3621&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:23.069 [0x7fd6726c8740] DEBUG - It took 10.000000 ms to retrieve 104 items.
Apr 21, 2019 00:08:23.074 [0x7fd6726c8740] DEBUG - Analyzing media parts for item 178 (Transformers: Revenge of the Fallen): 178
Apr 21, 2019 00:08:23.075 [0x7fd6726c8740] DEBUG - [ID 178] Media part analysis: /volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv
Apr 21, 2019 00:08:23.103 [0x7fd6726c8740] DEBUG - [MI] Opening input file: "/volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv"
Apr 21, 2019 00:08:23.103 [0x7fd6726c8740] DEBUG - [FFMPEG] - Opening '/volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv' for reading
Apr 21, 2019 00:08:23.103 [0x7fd6726c8740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 21, 2019 00:08:23.129 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 21, 2019 00:08:23.136 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:23.136 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:23.136 [0x7fd6726c8740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5719 bytes read:145018 seeks:2 nb_streams:2
Apr 21, 2019 00:08:23.136 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:23.136 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:23.137 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:23.137 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:23.137 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:23.137 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 21, 2019 00:08:23.137 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 21, 2019 00:08:23.138 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Reinit context to 1280x720, pix_fmt: yuv420p
Apr 21, 2019 00:08:23.139 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:23.146 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:23.150 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.156 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.161 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.166 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:23.171 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.178 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.183 [0x7fd6726c8740] DEBUG - [FFMPEG] - All info found
Apr 21, 2019 00:08:23.183 [0x7fd6726c8740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 107440 bytes read:276090 seeks:2 frames:41
Apr 21, 2019 00:08:23.184 [0x7fd6726c8740] 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 21, 2019 00:08:23.184 [0x7fd6726c8740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 21, 2019 00:08:23.185 [0x7fd6726c8740] DEBUG - [MI] Closing input file: "/volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv"
Apr 21, 2019 00:08:23.185 [0x7fd6726c8740] DEBUG - [FFMPEG] - Statistics: 276090 bytes read, 2 seeks
Apr 21, 2019 00:08:23.187 [0x7fd6726c8740] DEBUG - Updating media item 178, size=9353710165, metadata_item_id=178
Apr 21, 2019 00:08:23.187 [0x7fd6726c8740] DEBUG - Updating part with ID=178 [/volume1/video/Transformers - Revenge Of The Fallen - 2009.mkv]
Apr 21, 2019 00:08:23.257 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=178&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:23.259 [0x7fd662c67700] 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 21, 2019 00:08:23.260 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=178&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:23.262 [0x7fd662c67700] 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 21, 2019 00:08:23.262 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=178&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:23.264 [0x7fd662c67700] 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 21, 2019 00:08:23.268 [0x7fd6726c8740] DEBUG - Analyzing media parts for item 180 (Truth or Dare): 180
Apr 21, 2019 00:08:23.268 [0x7fd6726c8740] DEBUG - [ID 180] Media part analysis: /volume1/video/Truth Or Dare - 2018.mkv
Apr 21, 2019 00:08:23.268 [0x7fd6726c8740] DEBUG - [MI] Opening input file: "/volume1/video/Truth Or Dare - 2018.mkv"
Apr 21, 2019 00:08:23.268 [0x7fd6726c8740] DEBUG - [FFMPEG] - Opening '/volume1/video/Truth Or Dare - 2018.mkv' for reading
Apr 21, 2019 00:08:23.268 [0x7fd6726c8740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 21, 2019 00:08:23.328 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 21, 2019 00:08:23.354 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:23.354 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:23.354 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:23.354 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:23.354 [0x7fd6726c8740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6848 bytes read:34509 seeks:2 nb_streams:4
Apr 21, 2019 00:08:23.354 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:23.354 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:23.355 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:23.355 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:23.356 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:23.356 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:23.356 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:23.356 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 21, 2019 00:08:23.356 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 21, 2019 00:08:23.356 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Reinit context to 1280x544, pix_fmt: yuv420p
Apr 21, 2019 00:08:23.358 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:23.365 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:23.366 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:23.374 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:23.383 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.393 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.402 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.410 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.418 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.427 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.436 [0x7fd6726c8740] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 41) in the queue
Apr 21, 2019 00:08:23.589 [0x7fd6726c8740] DEBUG - [FFMPEG] - Probe buffer size limit of 20000000 bytes reached
Apr 21, 2019 00:08:23.589 [0x7fd6726c8740] WARN - [FFMPEG] - Could not find codec parameters for stream 2 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Apr 21, 2019 00:08:23.589 [0x7fd6726c8740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 19929074 bytes read:19974086 seeks:2 frames:1750
Apr 21, 2019 00:08:23.589 [0x7fd6726c8740] DEBUG - [MI]  * 696e2438c71606b9e60b7a937468af7d017a4de1 [mkv h264/dca] Duration: 6001334ms, 1280x536 (2.388060) 23.976fps, bitrate=9498kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Truth Or Dare - 2018.mkv")
Apr 21, 2019 00:08:23.590 [0x7fd6726c8740] DEBUG - [MI] Importing 20 media chapters.
Apr 21, 2019 00:08:23.590 [0x7fd6726c8740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 21, 2019 00:08:23.591 [0x7fd6726c8740] DEBUG - [MI] Closing input file: "/volume1/video/Truth Or Dare - 2018.mkv"
Apr 21, 2019 00:08:23.592 [0x7fd6726c8740] DEBUG - [FFMPEG] - Statistics: 19974086 bytes read, 2 seeks
Apr 21, 2019 00:08:23.594 [0x7fd6726c8740] DEBUG - Updating media item 180, size=7125122636, metadata_item_id=180
Apr 21, 2019 00:08:23.595 [0x7fd6726c8740] DEBUG - Updating part with ID=180 [/volume1/video/Truth Or Dare - 2018.mkv]
Apr 21, 2019 00:08:23.724 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=180&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:23.726 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=180&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:23.726 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=180&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:23.727 [0x7fd6726c8740] VERBOSE - Skipping expensive tags write for 'Truth or Dare' (type: 9) since nothing changed.
Apr 21, 2019 00:08:23.728 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=180&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:23.729 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=180&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:23.730 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=180&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:23.734 [0x7fd6726c8740] DEBUG - Analyzing media parts for item 1185 (P!nk: The Truth About Love Tour - Live from Melbourne): 3114
Apr 21, 2019 00:08:23.734 [0x7fd6726c8740] DEBUG - [ID 3114] Media part analysis: /volume1/video/Pink - The Truth About Love Tour - Melbourne - 2013.mkv
Apr 21, 2019 00:08:23.755 [0x7fd6726c8740] DEBUG - [MI] Opening input file: "/volume1/video/Pink - The Truth About Love Tour - Melbourne - 2013.mkv"
Apr 21, 2019 00:08:23.755 [0x7fd6726c8740] DEBUG - [FFMPEG] - Opening '/volume1/video/Pink - The Truth About Love Tour - Melbourne - 2013.mkv' for reading
Apr 21, 2019 00:08:23.755 [0x7fd6726c8740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 21, 2019 00:08:23.819 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 21, 2019 00:08:23.819 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:23.819 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:23.819 [0x7fd6726c8740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6856 bytes read:32768 seeks:0 nb_streams:2
Apr 21, 2019 00:08:23.819 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:23.819 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:23.820 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:23.820 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:23.820 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:23.820 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 21, 2019 00:08:23.820 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 21, 2019 00:08:23.820 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Reinit context to 1280x720, pix_fmt: yuv420p
Apr 21, 2019 00:08:23.821 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:23.824 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:23.824 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:23.826 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:23.829 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.832 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.835 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:23.837 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:23.840 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.843 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:23.846 [0x7fd6726c8740] DEBUG - [FFMPEG] - All info found
Apr 21, 2019 00:08:23.846 [0x7fd6726c8740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 49271 bytes read:65536 seeks:0 frames:26
Apr 21, 2019 00:08:23.846 [0x7fd6726c8740] DEBUG - [MI]  * a16d56d6714cd2a5b40f2281ac0a191959911ca2 [mkv h264/ac3] Duration: 6630592ms, 1280x720 (1.777778) 23.976fps, bitrate=5664kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Pink - The Truth About Love Tour - Melbourne - 2013.mkv")
Apr 21, 2019 00:08:23.846 [0x7fd6726c8740] DEBUG - [MI] Importing 23 media chapters.
Apr 21, 2019 00:08:23.846 [0x7fd6726c8740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 21, 2019 00:08:23.847 [0x7fd6726c8740] DEBUG - [MI] Closing input file: "/volume1/video/Pink - The Truth About Love Tour - Melbourne - 2013.mkv"
Apr 21, 2019 00:08:23.847 [0x7fd6726c8740] DEBUG - [FFMPEG] - Statistics: 65536 bytes read, 0 seeks
Apr 21, 2019 00:08:23.849 [0x7fd6726c8740] DEBUG - Updating media item 3114, size=4694617865, metadata_item_id=1185
Apr 21, 2019 00:08:23.850 [0x7fd6726c8740] DEBUG - Updating part with ID=3114 [/volume1/video/Pink - The Truth About Love Tour - Melbourne - 2013.mkv]
Apr 21, 2019 00:08:23.944 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=1185&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:23.947 [0x7fd6726c8740] VERBOSE - Skipping expensive tags write for 'P!nk: The Truth About Love Tour - Live from Melbourne' (type: 9) since nothing changed.
Apr 21, 2019 00:08:23.947 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=1185&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:23.947 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=1185&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:23.949 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=1185&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:23.949 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=1185&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:23.951 [0x7fd6726c8740] DEBUG - Analyzing media parts for item 17482 (Project Daedalus): 24747
Apr 21, 2019 00:08:23.951 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=1185&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:23.952 [0x7fd6726c8740] DEBUG - [ID 24853] Media part analysis: /volume1/shows/Star Trek - Discovery - S02E09.mkv
Apr 21, 2019 00:08:23.952 [0x7fd6726c8740] DEBUG - [MI] Opening input file: "/volume1/shows/Star Trek - Discovery - S02E09.mkv"
Apr 21, 2019 00:08:23.952 [0x7fd6726c8740] DEBUG - [FFMPEG] - Opening '/volume1/shows/Star Trek - Discovery - S02E09.mkv' for reading
Apr 21, 2019 00:08:23.952 [0x7fd6726c8740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 21, 2019 00:08:23.988 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 21, 2019 00:08:24.017 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:24.017 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:24.017 [0x7fd6726c8740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6143 bytes read:33562 seeks:2 nb_streams:2
Apr 21, 2019 00:08:24.017 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:24.018 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:24.018 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:24.018 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:24.018 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 21, 2019 00:08:24.018 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 21, 2019 00:08:24.018 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Reinit context to 1920x1088, pix_fmt: yuv420p
Apr 21, 2019 00:08:24.020 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:24.024 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.025 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:24.029 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.035 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.041 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.050 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.054 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.059 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.065 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.071 [0x7fd6726c8740] DEBUG - [FFMPEG] - All info found
Apr 21, 2019 00:08:24.071 [0x7fd6726c8740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 48252 bytes read:99098 seeks:2 frames:25
Apr 21, 2019 00:08:24.071 [0x7fd6726c8740] DEBUG - [MI]  * c529eb8cd960fd7c3108b47e0b6c486269e081ee [mkv h264/eac3] Duration: 3168640ms, 1920x1080 (1.777778) 24.000fps, bitrate=9875kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/shows/Star Trek - Discovery - S02E09.mkv")
Apr 21, 2019 00:08:24.071 [0x7fd6726c8740] DEBUG - [MI] Importing 9 media chapters.
Apr 21, 2019 00:08:24.072 [0x7fd6726c8740] DEBUG - [MI] Closing input file: "/volume1/shows/Star Trek - Discovery - S02E09.mkv"
Apr 21, 2019 00:08:24.072 [0x7fd6726c8740] DEBUG - [FFMPEG] - Statistics: 99098 bytes read, 2 seeks
Apr 21, 2019 00:08:24.074 [0x7fd6726c8740] DEBUG - Updating media item 24747, size=3911580803, metadata_item_id=17482
Apr 21, 2019 00:08:24.074 [0x7fd6726c8740] DEBUG - Updating part with ID=24853 [/volume1/shows/Star Trek - Discovery - S02E09.mkv]
Apr 21, 2019 00:08:24.146 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=17482&metadataType=4&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:24.147 [0x7fd6726c8740] VERBOSE - Skipping expensive tags write for 'Project Daedalus' (type: 9) since nothing changed.
Apr 21, 2019 00:08:24.148 [0x7fd6726c8740] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Apr 21, 2019 00:08:24.148 [0x7fd6726c8740] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Apr 21, 2019 00:08:24.148 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=17482&metadataType=4&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:24.148 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=17482&metadataType=4&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:24.151 [0x7fd6726c8740] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Apr 21, 2019 00:08:24.151 [0x7fd6726c8740] DEBUG - Updating metadata item (save) (Project Daedalus) with ID 17482
Apr 21, 2019 00:08:24.152 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=17482&metadataType=4&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:24.152 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=17482&metadataType=4&state=-1&mediaState=idle
Apr 21, 2019 00:08:24.154 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=17482&metadataType=4&state=-1&mediaState=idle
Apr 21, 2019 00:08:24.212 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Apr 21, 2019 00:08:24.213 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Apr 21, 2019 00:08:24.224 [0x7fd6726c8740] DEBUG - Analyzing media parts for item 91 (Mark Felt: The Man Who Brought Down the White House): 91
Apr 21, 2019 00:08:24.224 [0x7fd6726c8740] DEBUG - [ID 91] Media part analysis: /volume1/video/Mark Felt - 2017.mkv
Apr 21, 2019 00:08:24.224 [0x7fd6726c8740] DEBUG - [MI] Opening input file: "/volume1/video/Mark Felt - 2017.mkv"
Apr 21, 2019 00:08:24.224 [0x7fd6726c8740] DEBUG - [FFMPEG] - Opening '/volume1/video/Mark Felt - 2017.mkv' for reading
Apr 21, 2019 00:08:24.224 [0x7fd6726c8740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 21, 2019 00:08:24.273 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 21, 2019 00:08:24.320 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:24.320 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:24.321 [0x7fd6726c8740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6484 bytes read:33605 seeks:2 nb_streams:2
Apr 21, 2019 00:08:24.321 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:24.321 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:24.321 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:24.321 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:24.322 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:24.322 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 21, 2019 00:08:24.322 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 21, 2019 00:08:24.322 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Reinit context to 1920x960, pix_fmt: yuv420p
Apr 21, 2019 00:08:24.324 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:24.330 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.331 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:24.336 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.343 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.349 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.356 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.361 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.367 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.372 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.387 [0x7fd6726c8740] DEBUG - [FFMPEG] - All info found
Apr 21, 2019 00:08:24.387 [0x7fd6726c8740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 129238 bytes read:164677 seeks:2 frames:58
Apr 21, 2019 00:08:24.387 [0x7fd6726c8740] DEBUG - [MI]  * 2cb9c2f6c38d1ef36bc4fd863d442c77a5796a18 [mkv h264/dca] Duration: 6177174ms, 1920x960 (2.000000) 23.976fps, bitrate=10641kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Mark Felt - 2017.mkv")
Apr 21, 2019 00:08:24.387 [0x7fd6726c8740] DEBUG - [MI] Importing 16 media chapters.
Apr 21, 2019 00:08:24.387 [0x7fd6726c8740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 21, 2019 00:08:24.388 [0x7fd6726c8740] DEBUG - [MI] Closing input file: "/volume1/video/Mark Felt - 2017.mkv"
Apr 21, 2019 00:08:24.388 [0x7fd6726c8740] DEBUG - [FFMPEG] - Statistics: 164677 bytes read, 2 seeks
Apr 21, 2019 00:08:24.390 [0x7fd6726c8740] DEBUG - Updating media item 91, size=8216930329, metadata_item_id=91
Apr 21, 2019 00:08:24.390 [0x7fd6726c8740] DEBUG - Updating part with ID=91 [/volume1/video/Mark Felt - 2017.mkv]
Apr 21, 2019 00:08:24.479 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=91&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:24.482 [0x7fd6726c8740] VERBOSE - Skipping expensive tags write for 'Mark Felt: The Man Who Brought Down the White House' (type: 9) since nothing changed.
Apr 21, 2019 00:08:24.482 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=91&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:24.483 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=91&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:24.484 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=91&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:24.485 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=91&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:24.486 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=91&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:24.492 [0x7fd6726c8740] DEBUG - Analyzing media parts for item 2640 (Winchester): 5076
Apr 21, 2019 00:08:24.492 [0x7fd6726c8740] DEBUG - [ID 5076] Media part analysis: /volume1/video/Winchester - 2018.mkv
Apr 21, 2019 00:08:24.492 [0x7fd6726c8740] DEBUG - [MI] Opening input file: "/volume1/video/Winchester - 2018.mkv"
Apr 21, 2019 00:08:24.492 [0x7fd6726c8740] DEBUG - [FFMPEG] - Opening '/volume1/video/Winchester - 2018.mkv' for reading
Apr 21, 2019 00:08:24.492 [0x7fd6726c8740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 21, 2019 00:08:24.555 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 21, 2019 00:08:24.614 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:24.614 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:24.614 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:24.614 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:24.614 [0x7fd6726c8740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6117 bytes read:34180 seeks:2 nb_streams:4
Apr 21, 2019 00:08:24.614 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:24.615 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:24.615 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:24.615 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:24.616 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:24.616 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 21, 2019 00:08:24.616 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 21, 2019 00:08:24.616 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Reinit context to 1920x816, pix_fmt: yuv420p
Apr 21, 2019 00:08:24.617 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:24.621 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.621 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:24.624 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.629 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.634 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.639 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.642 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.646 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.650 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.655 [0x7fd6726c8740] DEBUG - [FFMPEG] - All info found
Apr 21, 2019 00:08:24.655 [0x7fd6726c8740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 48758 bytes read:99716 seeks:2 frames:26
Apr 21, 2019 00:08:24.656 [0x7fd6726c8740] DEBUG - [MI]  * 7429bcaed344f5d78071aa37f10635b5bacaf63f [mkv h264/ac3] Duration: 5971966ms, 1920x808 (2.376238) 23.976fps, bitrate=9433kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Winchester - 2018.mkv")
Apr 21, 2019 00:08:24.656 [0x7fd6726c8740] DEBUG - [MI] Importing 8 media chapters.
Apr 21, 2019 00:08:24.656 [0x7fd6726c8740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 21, 2019 00:08:24.657 [0x7fd6726c8740] DEBUG - [MI] Closing input file: "/volume1/video/Winchester - 2018.mkv"
Apr 21, 2019 00:08:24.657 [0x7fd6726c8740] DEBUG - [FFMPEG] - Statistics: 99716 bytes read, 2 seeks
Apr 21, 2019 00:08:24.658 [0x7fd6726c8740] DEBUG - Committing media stream changes for part 5076
Apr 21, 2019 00:08:24.712 [0x7fd6726c8740] DEBUG - Updating media item 5076, size=7042320392, metadata_item_id=2640
Apr 21, 2019 00:08:24.712 [0x7fd6726c8740] DEBUG - Updating part with ID=5076 [/volume1/video/Winchester - 2018.mkv]
Apr 21, 2019 00:08:24.803 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2640&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:24.804 [0x7fd6726c8740] VERBOSE - Skipping expensive tags write for 'Winchester' (type: 9) since nothing changed.
Apr 21, 2019 00:08:24.805 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2640&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:24.805 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2640&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:24.807 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2640&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:24.808 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2640&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:24.810 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2640&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:24.817 [0x7fd6726c8740] DEBUG - It took 0.000000 ms to retrieve 202 items.
Apr 21, 2019 00:08:24.824 [0x7fd6726c8740] DEBUG - Analyzing media parts for item 3426 (Rogue One: A Star Wars Story): 7268
Apr 21, 2019 00:08:24.824 [0x7fd6726c8740] DEBUG - [ID 7268] Media part analysis: /volume1/video/Rogue One - 2016.mkv
Apr 21, 2019 00:08:24.828 [0x7fd6726c8740] DEBUG - [MI] Opening input file: "/volume1/video/Rogue One - 2016.mkv"
Apr 21, 2019 00:08:24.828 [0x7fd6726c8740] DEBUG - [FFMPEG] - Opening '/volume1/video/Rogue One - 2016.mkv' for reading
Apr 21, 2019 00:08:24.828 [0x7fd6726c8740] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Apr 21, 2019 00:08:24.837 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Apr 21, 2019 00:08:24.843 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:24.843 [0x7fd6726c8740] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Apr 21, 2019 00:08:24.843 [0x7fd6726c8740] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 8130 bytes read:33605 seeks:2 nb_streams:2
Apr 21, 2019 00:08:24.843 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:24.843 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:24.844 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Apr 21, 2019 00:08:24.844 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Apr 21, 2019 00:08:24.844 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Apr 21, 2019 00:08:24.844 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Apr 21, 2019 00:08:24.844 [0x7fd6726c8740] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Apr 21, 2019 00:08:24.844 [0x7fd6726c8740] VERBOSE - [FFMPEG] - Reinit context to 1920x816, pix_fmt: yuv420p
Apr 21, 2019 00:08:24.846 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:24.864 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.865 [0x7fd6726c8740] DEBUG - [FFMPEG] - no picture 
Apr 21, 2019 00:08:24.869 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.874 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.879 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.885 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.889 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Apr 21, 2019 00:08:24.895 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.900 [0x7fd6726c8740] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Apr 21, 2019 00:08:24.907 [0x7fd6726c8740] DEBUG - [FFMPEG] - All info found
Apr 21, 2019 00:08:24.907 [0x7fd6726c8740] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 169727 bytes read:197445 seeks:2 frames:58
Apr 21, 2019 00:08:24.907 [0x7fd6726c8740] DEBUG - [MI]  * 4e9642de4d65ce61ffc9f9b403bf11e211b7d737 [mkv h264/dca] Duration: 8037280ms, 1920x808 (2.376238) 23.976fps, bitrate=10516kbps, audio channels=6, optimizedForStreaming=0 ("/volume1/video/Rogue One - 2016.mkv")
Apr 21, 2019 00:08:24.907 [0x7fd6726c8740] DEBUG - [MI] Importing 49 media chapters.
Apr 21, 2019 00:08:24.908 [0x7fd6726c8740] DEBUG - [MI] Chapters had generic titles, clearing.
Apr 21, 2019 00:08:24.909 [0x7fd6726c8740] DEBUG - [MI] Closing input file: "/volume1/video/Rogue One - 2016.mkv"
Apr 21, 2019 00:08:24.909 [0x7fd6726c8740] DEBUG - [FFMPEG] - Statistics: 197445 bytes read, 2 seeks
Apr 21, 2019 00:08:24.912 [0x7fd6726c8740] DEBUG - Updating media item 7268, size=10565482757, metadata_item_id=3426
Apr 21, 2019 00:08:24.912 [0x7fd6726c8740] DEBUG - Updating part with ID=7268 [/volume1/video/Rogue One - 2016.mkv]
Apr 21, 2019 00:08:24.957 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3426&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:24.959 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3426&metadataType=1&state=-1&mediaState=analyzing
Apr 21, 2019 00:08:24.959 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3426&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:24.961 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3426&metadataType=1&state=-1&mediaState=thumbnailing
Apr 21, 2019 00:08:24.962 [0x7fd662c67700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3426&metadataType=1&state=-1&mediaState=idle
Apr 21, 2019 00:08:24.963 [0x7fd6726c8740] VERBOSE - Skipping expensive tags write for 'Rogue One: A Star Wars Story' (type: 9) since nothing changed.
Apr 21, 2019 00:08:24.963 [0x7fd662c67700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3426&metadataType=1&state=-1&mediaState=idle
