Dec 12, 2018 02:01:05.491 [0x7f374dfff700] INFO - Plex Media Scanner v1.14.0.5470-9d51fdfaa - Docker Docker Container x86_64 - build: linux-ubuntu-x86_64 - GMT 01:00
Dec 12, 2018 02:01:05.491 [0x7f374dfff700] INFO - Linux version: 4.15.0-42-generic, language: C
Dec 12, 2018 02:01:05.491 [0x7f374dfff700] INFO - Processor AMD Ryzen 7 2700X Eight-Core Processor         
Dec 12, 2018 02:01:05.491 [0x7f374dfff700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 57119,57117,56412,78332,56410,56409,57118,78331,56413,56407 --log-file-suffix  Analysis
Dec 12, 2018 02:01:05.496 [0x7f3759986800] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Dec 12, 2018 02:01:05.588 [0x7f3759986800] DEBUG - Analyzing media parts for item 57119 (Blood Must Have Blood (2)): 276225,490176
Dec 12, 2018 02:01:05.589 [0x7f3759986800] DEBUG - [ID 501627] Media part analysis: /unionfs/TV/The 100/Season 2/The.100.S02E16.720p.BluRay.X264-REWARD.mkv
Dec 12, 2018 02:01:05.590 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/The 100/Season 2/The.100.S02E16.720p.BluRay.X264-REWARD.mkv"
Dec 12, 2018 02:01:05.590 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/The 100/Season 2/The.100.S02E16.720p.BluRay.X264-REWARD.mkv' for reading
Dec 12, 2018 02:01:05.590 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:06.184 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:06.737 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:06.737 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:06.737 [0x7f3759986800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:06.737 [0x7f3759986800] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:06.737 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6110 bytes read:34290 seeks:2 nb_streams:4
Dec 12, 2018 02:01:06.737 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:06.738 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:06.738 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:06.738 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:06.739 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:06.739 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:06.739 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:06.739 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:06.743 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:06.744 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:06.744 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:06.745 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:06.746 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:06.748 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:06.749 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:06.749 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:06.750 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:06.751 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:06.752 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 104121 bytes read:132594 seeks:2 frames:58
Dec 12, 2018 02:01:06.753 [0x7f3759986800] DEBUG - [MI]  * 38311998c4efeb968c487dbef65a8aeee9a7bc70 [mkv h264/dca] Duration: 2549152ms, 1280x720 (1.777778) 23.976fps, bitrate=7354kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/The 100/Season 2/The.100.S02E16.720p.BluRay.X264-REWARD.mkv")
Dec 12, 2018 02:01:06.753 [0x7f3759986800] DEBUG - [MI] Importing 8 media chapters.
Dec 12, 2018 02:01:06.754 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:06.754 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:06.754 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:06.756 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:06.756 [0x7f3759986800] DEBUG - Updating metadata item (save) (Blood Must Have Blood (2)) with ID 57119
Dec 12, 2018 02:01:06.761 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:06.762 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Blood Must Have Blood (2)' because something changed.
Dec 12, 2018 02:01:06.762 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:06.765 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:06.765 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:06.766 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:06.766 [0x7f3759986800] DEBUG - Updating metadata item (save) (Blood Must Have Blood (2)) with ID 57119
Dec 12, 2018 02:01:06.769 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/The 100/Season 2/The.100.S02E16.720p.BluRay.X264-REWARD.mkv"
Dec 12, 2018 02:01:06.769 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:06.770 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:06.770 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 132594 bytes read, 2 seeks
Dec 12, 2018 02:01:06.770 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:06.770 [0x7f3759986800] DEBUG - Updating media item 276225, size=2343529385, metadata_item_id=57119
Dec 12, 2018 02:01:06.771 [0x7f3759986800] DEBUG - Updating part with ID=501627 [/unionfs/TV/The 100/Season 2/The.100.S02E16.720p.BluRay.X264-REWARD.mkv]
Dec 12, 2018 02:01:06.773 [0x7f3759986800] DEBUG - [ID 501624] Media part analysis: /unionfs/TV/The 100/Season 2/The 100 - 2x16.mkv
Dec 12, 2018 02:01:06.773 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:06.774 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:06.774 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:06.774 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/The 100/Season 2/The 100 - 2x16.mkv"
Dec 12, 2018 02:01:06.774 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/The 100/Season 2/The 100 - 2x16.mkv' for reading
Dec 12, 2018 02:01:06.775 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:06.775 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:06.775 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:06.776 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:07.715 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:07.716 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:07.716 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:07.716 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5591 bytes read:32768 seeks:0 nb_streams:2
Dec 12, 2018 02:01:07.716 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:07.716 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:07.716 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:07.716 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:07.716 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:07.717 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:07.717 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:07.717 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:07.722 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:07.723 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:07.728 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:07.732 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:07.736 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:07.740 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:07.745 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:07.749 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:07.753 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:07.757 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:07.762 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 316585 bytes read:336843 seeks:0 frames:26
Dec 12, 2018 02:01:07.762 [0x7f3759986800] DEBUG - [MI]  * 5b01b447d001d73cd029a92321cbda74b9a5d344 [mkv h264/ac3] Duration: 2508480ms, 1280x720 (1.777778) 23.976fps, bitrate=5404kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/The 100/Season 2/The 100 - 2x16.mkv")
Dec 12, 2018 02:01:07.762 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:07.763 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:07.763 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:07.764 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:07.764 [0x7f3759986800] DEBUG - Updating metadata item (save) (Blood Must Have Blood (2)) with ID 57119
Dec 12, 2018 02:01:07.766 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:07.767 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:07.767 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Blood Must Have Blood (2)' because something changed.
Dec 12, 2018 02:01:07.769 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:07.769 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:07.770 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:07.770 [0x7f3759986800] DEBUG - Updating metadata item (save) (Blood Must Have Blood (2)) with ID 57119
Dec 12, 2018 02:01:07.772 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/The 100/Season 2/The 100 - 2x16.mkv"
Dec 12, 2018 02:01:07.772 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:07.772 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:07.773 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 336843 bytes read, 0 seeks
Dec 12, 2018 02:01:07.773 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:07.773 [0x7f3759986800] DEBUG - Updating media item 490176, size=1694615759, metadata_item_id=57119
Dec 12, 2018 02:01:07.774 [0x7f3759986800] DEBUG - Updating part with ID=501624 [/unionfs/TV/The 100/Season 2/The 100 - 2x16.mkv]
Dec 12, 2018 02:01:07.775 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:07.776 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:07.776 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:07.777 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:07.777 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:07.778 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57119&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:07.778 [0x7f3759986800] DEBUG - Analyzing media parts for item 57117 (Bodyguard of Lies): 276223,490174
Dec 12, 2018 02:01:07.779 [0x7f3759986800] DEBUG - [ID 501625] Media part analysis: /unionfs/TV/The 100/Season 2/The.100.S02E14.720p.BluRay.X264-REWARD.mkv
Dec 12, 2018 02:01:07.780 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/The 100/Season 2/The.100.S02E14.720p.BluRay.X264-REWARD.mkv"
Dec 12, 2018 02:01:07.780 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/The 100/Season 2/The.100.S02E14.720p.BluRay.X264-REWARD.mkv' for reading
Dec 12, 2018 02:01:07.780 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:08.712 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:09.133 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:09.133 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:09.133 [0x7f3759986800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:09.133 [0x7f3759986800] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:09.134 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6111 bytes read:34290 seeks:2 nb_streams:4
Dec 12, 2018 02:01:09.134 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:09.134 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:09.134 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:09.134 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:09.134 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:09.134 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:09.134 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:09.135 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:09.138 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:09.139 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:09.140 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:09.141 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:09.142 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:09.144 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:09.145 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:09.145 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:09.146 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:09.147 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:09.148 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 104123 bytes read:132594 seeks:2 frames:58
Dec 12, 2018 02:01:09.149 [0x7f3759986800] DEBUG - [MI]  * 4a51887dd68c689b3b1c87099639ac270e7e7234 [mkv h264/dca] Duration: 2545024ms, 1280x720 (1.777778) 23.976fps, bitrate=7369kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/The 100/Season 2/The.100.S02E14.720p.BluRay.X264-REWARD.mkv")
Dec 12, 2018 02:01:09.149 [0x7f3759986800] DEBUG - [MI] Importing 8 media chapters.
Dec 12, 2018 02:01:09.149 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:09.149 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.149 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.151 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.151 [0x7f3759986800] DEBUG - Updating metadata item (save) (Bodyguard of Lies) with ID 57117
Dec 12, 2018 02:01:09.154 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:09.154 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Bodyguard of Lies' because something changed.
Dec 12, 2018 02:01:09.154 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:09.158 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.158 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.159 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.159 [0x7f3759986800] DEBUG - Updating metadata item (save) (Bodyguard of Lies) with ID 57117
Dec 12, 2018 02:01:09.162 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/The 100/Season 2/The.100.S02E14.720p.BluRay.X264-REWARD.mkv"
Dec 12, 2018 02:01:09.162 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:09.162 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:09.163 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 132594 bytes read, 2 seeks
Dec 12, 2018 02:01:09.163 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:09.163 [0x7f3759986800] DEBUG - Updating media item 276223, size=2344507822, metadata_item_id=57117
Dec 12, 2018 02:01:09.163 [0x7f3759986800] DEBUG - Updating part with ID=501625 [/unionfs/TV/The 100/Season 2/The.100.S02E14.720p.BluRay.X264-REWARD.mkv]
Dec 12, 2018 02:01:09.166 [0x7f3759986800] DEBUG - [ID 501622] Media part analysis: /unionfs/TV/The 100/Season 2/The 100 - 2x14.mkv
Dec 12, 2018 02:01:09.166 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:09.167 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:09.167 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/The 100/Season 2/The 100 - 2x14.mkv"
Dec 12, 2018 02:01:09.167 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/The 100/Season 2/The 100 - 2x14.mkv' for reading
Dec 12, 2018 02:01:09.167 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:09.167 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:09.168 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:09.168 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:09.169 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:09.705 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:09.705 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:09.706 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:09.706 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5591 bytes read:32768 seeks:0 nb_streams:2
Dec 12, 2018 02:01:09.706 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:09.706 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:09.706 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:09.706 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:09.706 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:09.706 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:09.706 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:09.707 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:09.712 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:09.712 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:09.717 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:09.721 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:09.725 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:09.729 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:09.733 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:09.737 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:09.740 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:09.745 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:09.746 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 269155 bytes read:296646 seeks:0 frames:26
Dec 12, 2018 02:01:09.746 [0x7f3759986800] DEBUG - [MI]  * 97d5d4ff40783f496a7697d7c7f0bf370bd84d2a [mkv h264/ac3] Duration: 2503648ms, 1280x720 (1.777778) 23.976fps, bitrate=4460kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/The 100/Season 2/The 100 - 2x14.mkv")
Dec 12, 2018 02:01:09.747 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:09.747 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.747 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.748 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.749 [0x7f3759986800] DEBUG - Updating metadata item (save) (Bodyguard of Lies) with ID 57117
Dec 12, 2018 02:01:09.750 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:09.751 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:09.751 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Bodyguard of Lies' because something changed.
Dec 12, 2018 02:01:09.753 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.753 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.754 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:09.754 [0x7f3759986800] DEBUG - Updating metadata item (save) (Bodyguard of Lies) with ID 57117
Dec 12, 2018 02:01:09.756 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/The 100/Season 2/The 100 - 2x14.mkv"
Dec 12, 2018 02:01:09.756 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:09.756 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:09.757 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 296646 bytes read, 0 seeks
Dec 12, 2018 02:01:09.757 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:09.758 [0x7f3759986800] DEBUG - Updating media item 490174, size=1395824166, metadata_item_id=57117
Dec 12, 2018 02:01:09.758 [0x7f3759986800] DEBUG - Updating part with ID=501622 [/unionfs/TV/The 100/Season 2/The 100 - 2x14.mkv]
Dec 12, 2018 02:01:09.761 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:09.761 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:09.762 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:09.762 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:09.763 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:09.764 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57117&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:09.764 [0x7f3759986800] DEBUG - Analyzing media parts for item 56412 (Bingo): 155345,155344
Dec 12, 2018 02:01:09.765 [0x7f3759986800] DEBUG - [ID 501838] Media part analysis: /unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.mkv
Dec 12, 2018 02:01:09.766 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.mkv"
Dec 12, 2018 02:01:09.766 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.mkv' for reading
Dec 12, 2018 02:01:09.766 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:10.345 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:10.346 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:10.346 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:10.346 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5596 bytes read:32768 seeks:0 nb_streams:2
Dec 12, 2018 02:01:10.346 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:10.346 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:10.346 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:10.346 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:10.347 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:10.347 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:10.347 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:10.347 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:10.348 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:10.349 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:10.349 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:10.350 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:10.352 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:10.353 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:10.354 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:10.354 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:10.355 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:10.357 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:10.357 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 31520 bytes read:32768 seeks:0 frames:26
Dec 12, 2018 02:01:10.357 [0x7f3759986800] DEBUG - [MI]  * f46b43cfce0f4db2c5a4d702370bfb08e2bd8157 [mkv h264/ac3] Duration: 2822114ms, 1280x720 (1.777778) 23.976fps, bitrate=2288kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.mkv")
Dec 12, 2018 02:01:10.357 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:10.358 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:10.358 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:10.359 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:10.359 [0x7f3759986800] DEBUG - Updating metadata item (save) (Bingo) with ID 56412
Dec 12, 2018 02:01:10.362 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:10.363 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Bingo' because something changed.
Dec 12, 2018 02:01:10.363 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:10.365 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:10.365 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:10.366 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:10.366 [0x7f3759986800] DEBUG - Updating metadata item (save) (Bingo) with ID 56412
Dec 12, 2018 02:01:10.369 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.mkv"
Dec 12, 2018 02:01:10.369 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:10.370 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:10.370 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Dec 12, 2018 02:01:10.371 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:10.371 [0x7f3759986800] DEBUG - Updating media item 155345, size=807149835, metadata_item_id=56412
Dec 12, 2018 02:01:10.371 [0x7f3759986800] DEBUG - Updating part with ID=501838 [/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.mkv]
Dec 12, 2018 02:01:10.374 [0x7f3759986800] DEBUG - [ID 501837] Media part analysis: /unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.1.mkv
Dec 12, 2018 02:01:10.374 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:10.375 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:10.375 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.1.mkv"
Dec 12, 2018 02:01:10.375 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.1.mkv' for reading
Dec 12, 2018 02:01:10.375 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:10.375 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:10.376 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:10.376 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:10.377 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:11.507 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:11.508 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:11.508 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:11.508 [0x7f3759986800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:11.508 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5886 bytes read:32768 seeks:0 nb_streams:3
Dec 12, 2018 02:01:11.508 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:11.508 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:11.510 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:11.510 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:11.510 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:11.510 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:11.510 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:11.510 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:11.511 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:11.511 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:11.511 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:11.512 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:11.513 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:11.513 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:11.514 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:11.515 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:11.515 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:11.516 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:11.516 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 7916 bytes read:32768 seeks:0 frames:21
Dec 12, 2018 02:01:11.516 [0x7f3759986800] DEBUG - [MI]  * efcce745e8dcd2510c38f0f8ac8fb5f8cb2497a3 [mkv h264/aac] Duration: 2822166ms, 854x480 (1.779167) 23.976fps, bitrate=333kbps, audio channels=2, optimizedForStreaming=0 ("/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.1.mkv")
Dec 12, 2018 02:01:11.516 [0x7f3759986800] DEBUG - [MI] Importing 1 media chapters.
Dec 12, 2018 02:01:11.516 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:11.516 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:11.516 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:11.518 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:11.518 [0x7f3759986800] DEBUG - Updating metadata item (save) (Bingo) with ID 56412
Dec 12, 2018 02:01:11.521 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:11.521 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Bingo' because something changed.
Dec 12, 2018 02:01:11.521 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:11.523 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:11.524 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:11.525 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:11.525 [0x7f3759986800] DEBUG - Updating metadata item (save) (Bingo) with ID 56412
Dec 12, 2018 02:01:11.527 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.1.mkv"
Dec 12, 2018 02:01:11.527 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:11.528 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:11.528 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Dec 12, 2018 02:01:11.529 [0x7f3759986800] DEBUG - Committing media stream changes for part 501837
Dec 12, 2018 02:01:11.530 [0x7f3759986800] DEBUG - Updating media item 155344, size=117520675, metadata_item_id=56412
Dec 12, 2018 02:01:11.530 [0x7f3759986800] DEBUG - Updating part with ID=501837 [/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x07.1.mkv]
Dec 12, 2018 02:01:11.532 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:11.533 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:11.533 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:11.534 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:11.534 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:11.535 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56412&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:11.537 [0x7f3759986800] DEBUG - Analyzing media parts for item 78332 (Executables): 218108
Dec 12, 2018 02:01:11.537 [0x7f3759986800] DEBUG - [ID 498772] Media part analysis: /unionfs/TV/Incorporated/Season 1/Incorporated.S01E07.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv
Dec 12, 2018 02:01:11.538 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Incorporated/Season 1/Incorporated.S01E07.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv"
Dec 12, 2018 02:01:11.538 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Incorporated/Season 1/Incorporated.S01E07.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv' for reading
Dec 12, 2018 02:01:11.538 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:12.450 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7688 bytes read:33999 seeks:2 nb_streams:3
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:13.141 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:13.142 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:13.142 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:13.142 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:13.146 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:13.147 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:13.148 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:13.149 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:13.150 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:13.151 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:13.152 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:13.153 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:13.154 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 50068 bytes read:99535 seeks:2 frames:24
Dec 12, 2018 02:01:13.154 [0x7f3759986800] DEBUG - [MI]  * 121eb2da9f93e1184d67a18c063678d289ca01f3 [mkv h264/eac3] Duration: 2580288ms, 1280x720 (1.777778) 23.976fps, bitrate=5863kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/Incorporated/Season 1/Incorporated.S01E07.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv")
Dec 12, 2018 02:01:13.154 [0x7f3759986800] DEBUG - [MI] Importing 31 media chapters.
Dec 12, 2018 02:01:13.157 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:13.157 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:13.158 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:13.158 [0x7f3759986800] DEBUG - Updating metadata item (save) (Executables) with ID 78332
Dec 12, 2018 02:01:13.161 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Incorporated/Season 1/Incorporated.S01E07.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv"
Dec 12, 2018 02:01:13.161 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:13.162 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:13.162 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 99535 bytes read, 2 seeks
Dec 12, 2018 02:01:13.162 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:13.162 [0x7f3759986800] DEBUG - Updating media item 218108, size=1891033444, metadata_item_id=78332
Dec 12, 2018 02:01:13.163 [0x7f3759986800] DEBUG - Updating part with ID=498772 [/unionfs/TV/Incorporated/Season 1/Incorporated.S01E07.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv]
Dec 12, 2018 02:01:13.165 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78332&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:13.166 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78332&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:13.166 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78332&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:13.166 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78332&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:13.167 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78332&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:13.167 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78332&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:13.168 [0x7f3759986800] DEBUG - Analyzing media parts for item 56410 (Alpine Shepherd Boy): 155342,490173
Dec 12, 2018 02:01:13.169 [0x7f3759986800] DEBUG - [ID 501835] Media part analysis: /unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.mkv
Dec 12, 2018 02:01:13.170 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.mkv"
Dec 12, 2018 02:01:13.170 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.mkv' for reading
Dec 12, 2018 02:01:13.170 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:14.901 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:14.902 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:14.902 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:14.902 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5596 bytes read:32768 seeks:0 nb_streams:2
Dec 12, 2018 02:01:14.902 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:14.902 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:14.902 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:14.902 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:14.902 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:14.902 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:14.903 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:14.903 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:14.904 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:14.904 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:14.905 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:14.906 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:14.907 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:14.909 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:14.909 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:14.910 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:14.911 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:14.912 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:14.912 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 31520 bytes read:32768 seeks:0 frames:26
Dec 12, 2018 02:01:14.912 [0x7f3759986800] DEBUG - [MI]  * 370a14ce0ec5d55069763a696b60e53c6b599310 [mkv h264/ac3] Duration: 2652032ms, 1280x720 (1.777778) 23.976fps, bitrate=2213kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.mkv")
Dec 12, 2018 02:01:14.913 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:14.913 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:14.913 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:14.914 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:14.915 [0x7f3759986800] DEBUG - Updating metadata item (save) (Alpine Shepherd Boy) with ID 56410
Dec 12, 2018 02:01:14.917 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:14.918 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Alpine Shepherd Boy' because something changed.
Dec 12, 2018 02:01:14.918 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:14.920 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:14.920 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:14.921 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:14.921 [0x7f3759986800] DEBUG - Updating metadata item (save) (Alpine Shepherd Boy) with ID 56410
Dec 12, 2018 02:01:14.923 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.mkv"
Dec 12, 2018 02:01:14.923 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:14.924 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:14.925 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Dec 12, 2018 02:01:14.926 [0x7f3759986800] DEBUG - Updating media item 155342, size=733858196, metadata_item_id=56410
Dec 12, 2018 02:01:14.926 [0x7f3759986800] DEBUG - Updating part with ID=501835 [/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.mkv]
Dec 12, 2018 02:01:14.928 [0x7f3759986800] DEBUG - [ID 501834] Media part analysis: /unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.1.mkv
Dec 12, 2018 02:01:14.928 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:14.928 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:14.929 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:14.929 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.1.mkv"
Dec 12, 2018 02:01:14.929 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.1.mkv' for reading
Dec 12, 2018 02:01:14.929 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:14.929 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:14.929 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:14.930 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:15.397 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5892 bytes read:32768 seeks:0 nb_streams:3
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:15.398 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:15.399 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:15.399 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:15.399 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:15.399 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:15.400 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:15.401 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:15.402 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:15.402 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:15.403 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:15.404 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:15.404 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:15.404 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 7595 bytes read:32768 seeks:0 frames:26
Dec 12, 2018 02:01:15.404 [0x7f3759986800] DEBUG - [MI]  * d04566f41730aea56196189ad9ae2af151dac0fa [mkv h264/aac] Duration: 2652079ms, 854x480 (1.779167) 23.976fps, bitrate=363kbps, audio channels=2, optimizedForStreaming=0 ("/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.1.mkv")
Dec 12, 2018 02:01:15.405 [0x7f3759986800] DEBUG - [MI] Importing 1 media chapters.
Dec 12, 2018 02:01:15.405 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:15.405 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:15.405 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:15.406 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:15.406 [0x7f3759986800] DEBUG - Updating metadata item (save) (Alpine Shepherd Boy) with ID 56410
Dec 12, 2018 02:01:15.408 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:15.409 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Alpine Shepherd Boy' because something changed.
Dec 12, 2018 02:01:15.409 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:15.410 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:15.410 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:15.411 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:15.411 [0x7f3759986800] DEBUG - Updating metadata item (save) (Alpine Shepherd Boy) with ID 56410
Dec 12, 2018 02:01:15.414 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.1.mkv"
Dec 12, 2018 02:01:15.414 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:15.415 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Dec 12, 2018 02:01:15.415 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:15.416 [0x7f3759986800] DEBUG - Committing media stream changes for part 501834
Dec 12, 2018 02:01:15.418 [0x7f3759986800] DEBUG - Updating media item 490173, size=120521645, metadata_item_id=56410
Dec 12, 2018 02:01:15.418 [0x7f3759986800] DEBUG - Updating part with ID=501834 [/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x05.1.mkv]
Dec 12, 2018 02:01:15.421 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:15.421 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:15.422 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:15.423 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:15.423 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:15.423 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56410&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:15.424 [0x7f3759986800] DEBUG - Analyzing media parts for item 56409 (Hero): 155340,155339
Dec 12, 2018 02:01:15.425 [0x7f3759986800] DEBUG - [ID 501833] Media part analysis: /unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.mkv
Dec 12, 2018 02:01:15.426 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.mkv"
Dec 12, 2018 02:01:15.426 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.mkv' for reading
Dec 12, 2018 02:01:15.426 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:16.269 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:16.270 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:16.270 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:16.270 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5596 bytes read:32768 seeks:0 nb_streams:2
Dec 12, 2018 02:01:16.270 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:16.270 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:16.270 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:16.270 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:16.270 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:16.270 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:16.270 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:16.271 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:16.272 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:16.273 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:16.273 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:16.275 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:16.276 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:16.278 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:16.279 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:16.279 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:16.281 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:16.283 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:16.283 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 31520 bytes read:32768 seeks:0 frames:26
Dec 12, 2018 02:01:16.283 [0x7f3759986800] DEBUG - [MI]  * 120e05710c9efd253dfc124c3e235d074415983a [mkv h264/ac3] Duration: 2817026ms, 1280x720 (1.777778) 23.976fps, bitrate=2363kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.mkv")
Dec 12, 2018 02:01:16.283 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:16.284 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:16.284 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:16.285 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:16.285 [0x7f3759986800] DEBUG - Updating metadata item (save) (Hero) with ID 56409
Dec 12, 2018 02:01:16.288 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:16.288 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Hero' because something changed.
Dec 12, 2018 02:01:16.289 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:16.290 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:16.290 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:16.291 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:16.291 [0x7f3759986800] DEBUG - Updating metadata item (save) (Hero) with ID 56409
Dec 12, 2018 02:01:16.293 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.mkv"
Dec 12, 2018 02:01:16.293 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:16.294 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:16.294 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Dec 12, 2018 02:01:16.295 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:16.295 [0x7f3759986800] DEBUG - Updating media item 155340, size=832364930, metadata_item_id=56409
Dec 12, 2018 02:01:16.295 [0x7f3759986800] DEBUG - Updating part with ID=501833 [/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.mkv]
Dec 12, 2018 02:01:16.297 [0x7f3759986800] DEBUG - [ID 501832] Media part analysis: /unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.1.mkv
Dec 12, 2018 02:01:16.297 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:16.298 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:16.298 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:16.298 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.1.mkv"
Dec 12, 2018 02:01:16.298 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.1.mkv' for reading
Dec 12, 2018 02:01:16.298 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:16.299 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:16.299 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:16.299 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:17.227 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:17.228 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:17.228 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:17.228 [0x7f3759986800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:17.228 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5892 bytes read:32768 seeks:0 nb_streams:3
Dec 12, 2018 02:01:17.228 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:17.228 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:17.229 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:17.229 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:17.229 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:17.229 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:17.229 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:17.229 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:17.230 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:17.230 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:17.230 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:17.231 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:17.232 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:17.233 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:17.234 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:17.235 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:17.235 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:17.236 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:17.236 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 7656 bytes read:32768 seeks:0 frames:26
Dec 12, 2018 02:01:17.236 [0x7f3759986800] DEBUG - [MI]  * 91ade39a71fccabd2bb36afcf8a709e82b2cbc23 [mkv h264/aac] Duration: 2817078ms, 854x480 (1.779167) 23.976fps, bitrate=403kbps, audio channels=2, optimizedForStreaming=0 ("/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.1.mkv")
Dec 12, 2018 02:01:17.237 [0x7f3759986800] DEBUG - [MI] Importing 1 media chapters.
Dec 12, 2018 02:01:17.237 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:17.237 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:17.237 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:17.238 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:17.239 [0x7f3759986800] DEBUG - Updating metadata item (save) (Hero) with ID 56409
Dec 12, 2018 02:01:17.241 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:17.242 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Hero' because something changed.
Dec 12, 2018 02:01:17.242 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:17.244 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:17.244 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:17.245 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:17.246 [0x7f3759986800] DEBUG - Updating metadata item (save) (Hero) with ID 56409
Dec 12, 2018 02:01:17.247 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.1.mkv"
Dec 12, 2018 02:01:17.247 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:17.248 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:17.248 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Dec 12, 2018 02:01:17.249 [0x7f3759986800] DEBUG - Committing media stream changes for part 501832
Dec 12, 2018 02:01:17.250 [0x7f3759986800] DEBUG - Updating media item 155339, size=142052534, metadata_item_id=56409
Dec 12, 2018 02:01:17.251 [0x7f3759986800] DEBUG - Updating part with ID=501832 [/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x04.1.mkv]
Dec 12, 2018 02:01:17.253 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:17.253 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:17.254 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:17.254 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:17.255 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:17.255 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56409&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:17.256 [0x7f3759986800] DEBUG - Analyzing media parts for item 57118 (Blood Must Have Blood (1)): 276224,490175
Dec 12, 2018 02:01:17.257 [0x7f3759986800] DEBUG - [ID 501626] Media part analysis: /unionfs/TV/The 100/Season 2/The.100.S02E15.720p.BluRay.X264-REWARD.mkv
Dec 12, 2018 02:01:17.258 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/The 100/Season 2/The.100.S02E15.720p.BluRay.X264-REWARD.mkv"
Dec 12, 2018 02:01:17.258 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/The 100/Season 2/The.100.S02E15.720p.BluRay.X264-REWARD.mkv' for reading
Dec 12, 2018 02:01:17.258 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:18.157 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:18.532 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6111 bytes read:34290 seeks:2 nb_streams:4
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:18.533 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:18.534 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:18.538 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:18.538 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:18.539 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:18.540 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:18.542 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:18.544 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:18.544 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:18.545 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:18.546 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:18.548 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:18.549 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 104122 bytes read:132594 seeks:2 frames:58
Dec 12, 2018 02:01:18.549 [0x7f3759986800] DEBUG - [MI]  * 7482b3a0b603e58dd67a43cf3b6aedcdf38d8600 [mkv h264/dca] Duration: 2550454ms, 1280x720 (1.777778) 23.976fps, bitrate=7351kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/The 100/Season 2/The.100.S02E15.720p.BluRay.X264-REWARD.mkv")
Dec 12, 2018 02:01:18.549 [0x7f3759986800] DEBUG - [MI] Importing 8 media chapters.
Dec 12, 2018 02:01:18.549 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:18.550 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:18.550 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:18.551 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:18.551 [0x7f3759986800] DEBUG - Updating metadata item (save) (Blood Must Have Blood (1)) with ID 57118
Dec 12, 2018 02:01:18.554 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:18.554 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Blood Must Have Blood (1)' because something changed.
Dec 12, 2018 02:01:18.555 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:18.558 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:18.558 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:18.559 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:18.559 [0x7f3759986800] DEBUG - Updating metadata item (save) (Blood Must Have Blood (1)) with ID 57118
Dec 12, 2018 02:01:18.561 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/The 100/Season 2/The.100.S02E15.720p.BluRay.X264-REWARD.mkv"
Dec 12, 2018 02:01:18.561 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:18.562 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:18.562 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 132594 bytes read, 2 seeks
Dec 12, 2018 02:01:18.563 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:18.563 [0x7f3759986800] DEBUG - Updating media item 276224, size=2343835990, metadata_item_id=57118
Dec 12, 2018 02:01:18.563 [0x7f3759986800] DEBUG - Updating part with ID=501626 [/unionfs/TV/The 100/Season 2/The.100.S02E15.720p.BluRay.X264-REWARD.mkv]
Dec 12, 2018 02:01:18.565 [0x7f3759986800] DEBUG - [ID 501623] Media part analysis: /unionfs/TV/The 100/Season 2/The 100 - 2x15.mkv
Dec 12, 2018 02:01:18.565 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:18.565 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:18.566 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/The 100/Season 2/The 100 - 2x15.mkv"
Dec 12, 2018 02:01:18.566 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/The 100/Season 2/The 100 - 2x15.mkv' for reading
Dec 12, 2018 02:01:18.566 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:18.566 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:18.566 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:18.567 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:18.568 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:19.645 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:19.646 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:19.646 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:19.646 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5591 bytes read:32768 seeks:0 nb_streams:2
Dec 12, 2018 02:01:19.646 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:19.646 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:19.646 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:19.646 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:19.647 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:19.647 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:19.647 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:19.647 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:19.652 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:19.652 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:19.656 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:19.660 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:19.665 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:19.668 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:19.672 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:19.677 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:19.681 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:19.686 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:19.686 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 304386 bytes read:327680 seeks:0 frames:26
Dec 12, 2018 02:01:19.686 [0x7f3759986800] DEBUG - [MI]  * d85e3c70e7f48e883507a386c23e2e76d1ead824 [mkv h264/ac3] Duration: 2525824ms, 1280x720 (1.777778) 23.976fps, bitrate=4995kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/The 100/Season 2/The 100 - 2x15.mkv")
Dec 12, 2018 02:01:19.687 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:19.687 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:19.687 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:19.688 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:19.689 [0x7f3759986800] DEBUG - Updating metadata item (save) (Blood Must Have Blood (1)) with ID 57118
Dec 12, 2018 02:01:19.691 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:19.692 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:19.692 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Blood Must Have Blood (1)' because something changed.
Dec 12, 2018 02:01:19.694 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:19.694 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:19.695 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:19.696 [0x7f3759986800] DEBUG - Updating metadata item (save) (Blood Must Have Blood (1)) with ID 57118
Dec 12, 2018 02:01:19.698 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/The 100/Season 2/The 100 - 2x15.mkv"
Dec 12, 2018 02:01:19.698 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:19.699 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:19.699 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 327680 bytes read, 0 seeks
Dec 12, 2018 02:01:19.700 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:19.700 [0x7f3759986800] DEBUG - Updating media item 490175, size=1577161615, metadata_item_id=57118
Dec 12, 2018 02:01:19.700 [0x7f3759986800] DEBUG - Updating part with ID=501623 [/unionfs/TV/The 100/Season 2/The 100 - 2x15.mkv]
Dec 12, 2018 02:01:19.702 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:19.703 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:19.703 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:19.704 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:19.704 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:19.705 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=57118&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:19.706 [0x7f3759986800] DEBUG - Analyzing media parts for item 78331 (Vertical Mobility): 218107
Dec 12, 2018 02:01:19.706 [0x7f3759986800] DEBUG - [ID 498766] Media part analysis: /unionfs/TV/Incorporated/Season 1/Incorporated.S01E01.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv
Dec 12, 2018 02:01:19.707 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Incorporated/Season 1/Incorporated.S01E01.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv"
Dec 12, 2018 02:01:19.707 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Incorporated/Season 1/Incorporated.S01E01.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv' for reading
Dec 12, 2018 02:01:19.707 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:20.205 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:20.870 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:20.870 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:20.870 [0x7f3759986800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:20.870 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7231 bytes read:33996 seeks:2 nb_streams:3
Dec 12, 2018 02:01:20.870 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:20.870 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:20.870 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:20.870 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:20.870 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:20.870 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:20.871 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:20.871 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:20.871 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:20.871 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:20.874 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:20.876 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:20.878 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:20.880 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:20.881 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:20.882 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:20.883 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:20.884 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:20.885 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 49611 bytes read:99532 seeks:2 frames:24
Dec 12, 2018 02:01:20.885 [0x7f3759986800] DEBUG - [MI]  * 884f970d965c4dc2c4ae27a2c5a7998a4dfdac98 [mkv h264/eac3] Duration: 2787616ms, 1280x720 (1.777778) 23.976fps, bitrate=4976kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/Incorporated/Season 1/Incorporated.S01E01.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv")
Dec 12, 2018 02:01:20.885 [0x7f3759986800] DEBUG - [MI] Importing 23 media chapters.
Dec 12, 2018 02:01:20.888 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:20.888 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:20.889 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:20.889 [0x7f3759986800] DEBUG - Updating metadata item (save) (Vertical Mobility) with ID 78331
Dec 12, 2018 02:01:20.892 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Incorporated/Season 1/Incorporated.S01E01.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv"
Dec 12, 2018 02:01:20.892 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:20.893 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:20.893 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 99532 bytes read, 2 seeks
Dec 12, 2018 02:01:20.893 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:20.894 [0x7f3759986800] DEBUG - Updating media item 218107, size=1734156591, metadata_item_id=78331
Dec 12, 2018 02:01:20.894 [0x7f3759986800] DEBUG - Updating part with ID=498766 [/unionfs/TV/Incorporated/Season 1/Incorporated.S01E01.720p.AMZN.WEBRip.DD5.1.x264-KiNGS.mkv]
Dec 12, 2018 02:01:20.895 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78331&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:20.896 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78331&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:20.896 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78331&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:20.897 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78331&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:20.897 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78331&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:20.898 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78331&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:20.899 [0x7f3759986800] DEBUG - Analyzing media parts for item 56413 (RICO): 155347,155346
Dec 12, 2018 02:01:20.899 [0x7f3759986800] DEBUG - [ID 501840] Media part analysis: /unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.mkv
Dec 12, 2018 02:01:20.900 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.mkv"
Dec 12, 2018 02:01:20.900 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.mkv' for reading
Dec 12, 2018 02:01:20.900 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:21.739 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:21.740 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:21.740 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:21.740 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5596 bytes read:32768 seeks:0 nb_streams:2
Dec 12, 2018 02:01:21.740 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:21.740 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:21.740 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:21.740 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:21.741 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:21.741 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:21.741 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:21.741 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:21.742 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:21.743 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:21.743 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:21.745 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:21.746 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:21.748 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:21.749 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:21.750 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:21.751 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:21.753 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:21.753 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 31520 bytes read:32768 seeks:0 frames:26
Dec 12, 2018 02:01:21.753 [0x7f3759986800] DEBUG - [MI]  * bb1391deb06ca2c4b60b679d7e41a9bc4f5bbd1c [mkv h264/ac3] Duration: 2840736ms, 1280x720 (1.777778) 23.976fps, bitrate=2252kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.mkv")
Dec 12, 2018 02:01:21.754 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:21.754 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:21.754 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:21.756 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:21.756 [0x7f3759986800] DEBUG - Updating metadata item (save) (RICO) with ID 56413
Dec 12, 2018 02:01:21.758 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:21.758 [0x7f3759986800] DEBUG - Doing expensive tags write for 'RICO' because something changed.
Dec 12, 2018 02:01:21.758 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:21.760 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:21.760 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:21.761 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:21.761 [0x7f3759986800] DEBUG - Updating metadata item (save) (RICO) with ID 56413
Dec 12, 2018 02:01:21.762 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.mkv"
Dec 12, 2018 02:01:21.762 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:21.763 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:21.764 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Dec 12, 2018 02:01:21.765 [0x7f3759986800] DEBUG - Updating media item 155347, size=799816598, metadata_item_id=56413
Dec 12, 2018 02:01:21.765 [0x7f3759986800] DEBUG - Updating part with ID=501840 [/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.mkv]
Dec 12, 2018 02:01:21.768 [0x7f3759986800] DEBUG - [ID 501839] Media part analysis: /unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.1.mkv
Dec 12, 2018 02:01:21.768 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:21.768 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.1.mkv"
Dec 12, 2018 02:01:21.768 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.1.mkv' for reading
Dec 12, 2018 02:01:21.768 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:21.768 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:21.769 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:21.769 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:21.770 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:21.770 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:22.658 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5886 bytes read:32768 seeks:0 nb_streams:3
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:22.659 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:22.660 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:22.660 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:22.660 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:22.661 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:22.661 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:22.662 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:22.663 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:22.664 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:22.664 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:22.665 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:22.665 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:22.665 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 8509 bytes read:32768 seeks:0 frames:21
Dec 12, 2018 02:01:22.665 [0x7f3759986800] DEBUG - [MI]  * 152a8fd767c33bf2fd03332614f1849ff283ac44 [mkv h264/aac] Duration: 2840768ms, 854x480 (1.779167) 23.976fps, bitrate=403kbps, audio channels=2, optimizedForStreaming=0 ("/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.1.mkv")
Dec 12, 2018 02:01:22.666 [0x7f3759986800] DEBUG - [MI] Importing 1 media chapters.
Dec 12, 2018 02:01:22.666 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:22.666 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:22.666 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:22.667 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:22.668 [0x7f3759986800] DEBUG - Updating metadata item (save) (RICO) with ID 56413
Dec 12, 2018 02:01:22.682 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:22.682 [0x7f3759986800] DEBUG - Doing expensive tags write for 'RICO' because something changed.
Dec 12, 2018 02:01:22.682 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:22.686 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:22.686 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:22.687 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:22.687 [0x7f3759986800] DEBUG - Updating metadata item (save) (RICO) with ID 56413
Dec 12, 2018 02:01:22.690 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.1.mkv"
Dec 12, 2018 02:01:22.690 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:22.691 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:22.691 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Dec 12, 2018 02:01:22.692 [0x7f3759986800] DEBUG - Committing media stream changes for part 501839
Dec 12, 2018 02:01:22.693 [0x7f3759986800] DEBUG - Updating media item 155346, size=143347750, metadata_item_id=56413
Dec 12, 2018 02:01:22.693 [0x7f3759986800] DEBUG - Updating part with ID=501839 [/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x08.1.mkv]
Dec 12, 2018 02:01:22.695 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:22.696 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:22.696 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:22.697 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:22.697 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:22.698 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56413&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:22.698 [0x7f3759986800] DEBUG - Analyzing media parts for item 56407 (Mijo): 155337,155336
Dec 12, 2018 02:01:22.699 [0x7f3759986800] DEBUG - [ID 501830] Media part analysis: /unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.mkv
Dec 12, 2018 02:01:22.700 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.mkv"
Dec 12, 2018 02:01:22.700 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.mkv' for reading
Dec 12, 2018 02:01:22.700 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:23.527 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:23.528 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:23.528 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:23.528 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5596 bytes read:32768 seeks:0 nb_streams:2
Dec 12, 2018 02:01:23.528 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:23.528 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:23.528 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:23.528 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:23.529 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:23.529 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:23.529 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:23.529 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:23.530 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:23.531 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:23.531 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:23.533 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:23.534 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:23.536 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:23.537 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:23.538 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:23.539 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:23.541 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:23.541 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 31520 bytes read:32768 seeks:0 frames:26
Dec 12, 2018 02:01:23.541 [0x7f3759986800] DEBUG - [MI]  * 54984a7adf5503f90562667e33e97d4ad30cb29a [mkv h264/ac3] Duration: 2772064ms, 1280x720 (1.777778) 23.976fps, bitrate=2681kbps, audio channels=6, optimizedForStreaming=0 ("/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.mkv")
Dec 12, 2018 02:01:23.542 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:23.542 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:23.542 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:23.543 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:23.544 [0x7f3759986800] DEBUG - Updating metadata item (save) (Mijo) with ID 56407
Dec 12, 2018 02:01:23.546 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:23.547 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Mijo' because something changed.
Dec 12, 2018 02:01:23.547 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:23.549 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:23.549 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:23.550 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:23.551 [0x7f3759986800] DEBUG - Updating metadata item (save) (Mijo) with ID 56407
Dec 12, 2018 02:01:23.553 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.mkv"
Dec 12, 2018 02:01:23.553 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:23.554 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:23.555 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Dec 12, 2018 02:01:23.555 [0x7f3759986800] DEBUG - Streams didn't change in analysis, not saving.
Dec 12, 2018 02:01:23.556 [0x7f3759986800] DEBUG - Updating media item 155337, size=929329727, metadata_item_id=56407
Dec 12, 2018 02:01:23.556 [0x7f3759986800] DEBUG - Updating part with ID=501830 [/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.mkv]
Dec 12, 2018 02:01:23.559 [0x7f3759986800] DEBUG - [ID 501829] Media part analysis: /unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.1.mkv
Dec 12, 2018 02:01:23.559 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:23.560 [0x7f3759986800] DEBUG - [MI] Opening input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.1.mkv"
Dec 12, 2018 02:01:23.560 [0x7f3759986800] DEBUG - [FFMPEG] - Opening '/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.1.mkv' for reading
Dec 12, 2018 02:01:23.560 [0x7f3759986800] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Dec 12, 2018 02:01:23.560 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:23.560 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:23.561 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:23.561 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:23.562 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:24.182 [0x7f3759986800] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Dec 12, 2018 02:01:24.183 [0x7f3759986800] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:24.183 [0x7f3759986800] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:24.183 [0x7f3759986800] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Dec 12, 2018 02:01:24.183 [0x7f3759986800] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5885 bytes read:32768 seeks:0 nb_streams:3
Dec 12, 2018 02:01:24.183 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:24.183 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:24.184 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Dec 12, 2018 02:01:24.184 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Dec 12, 2018 02:01:24.184 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Dec 12, 2018 02:01:24.184 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Dec 12, 2018 02:01:24.184 [0x7f3759986800] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Dec 12, 2018 02:01:24.184 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:24.185 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:24.185 [0x7f3759986800] DEBUG - [FFMPEG] - no picture 
Dec 12, 2018 02:01:24.185 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Dec 12, 2018 02:01:24.186 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:24.187 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:24.188 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:24.188 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:24.189 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:24.189 [0x7f3759986800] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Dec 12, 2018 02:01:24.190 [0x7f3759986800] DEBUG - [FFMPEG] - All info found
Dec 12, 2018 02:01:24.190 [0x7f3759986800] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 7733 bytes read:32768 seeks:0 frames:26
Dec 12, 2018 02:01:24.190 [0x7f3759986800] DEBUG - [MI]  * 916f8f2a6e2b14d7d4b8b98ff46c521b9de90b65 [mkv h264/aac] Duration: 2772116ms, 854x480 (1.779167) 23.976fps, bitrate=495kbps, audio channels=2, optimizedForStreaming=0 ("/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.1.mkv")
Dec 12, 2018 02:01:24.190 [0x7f3759986800] DEBUG - [MI] Importing 1 media chapters.
Dec 12, 2018 02:01:24.190 [0x7f3759986800] DEBUG - [MI] Chapters had generic titles, clearing.
Dec 12, 2018 02:01:24.191 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:24.191 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:24.192 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:24.192 [0x7f3759986800] DEBUG - Updating metadata item (save) (Mijo) with ID 56407
Dec 12, 2018 02:01:24.194 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:24.194 [0x7f3759986800] DEBUG - Doing expensive tags write for 'Mijo' because something changed.
Dec 12, 2018 02:01:24.195 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:24.196 [0x7f3759986800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:24.196 [0x7f3759986800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:24.197 [0x7f3759986800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 12, 2018 02:01:24.197 [0x7f3759986800] DEBUG - Updating metadata item (save) (Mijo) with ID 56407
Dec 12, 2018 02:01:24.199 [0x7f3759986800] DEBUG - [MI] Closing input file: "/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.1.mkv"
Dec 12, 2018 02:01:24.199 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:24.200 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/flushMetadataCounterCache
Dec 12, 2018 02:01:24.200 [0x7f3759986800] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Dec 12, 2018 02:01:24.201 [0x7f3759986800] DEBUG - Committing media stream changes for part 501829
Dec 12, 2018 02:01:24.203 [0x7f3759986800] DEBUG - Updating media item 155336, size=171835719, metadata_item_id=56407
Dec 12, 2018 02:01:24.203 [0x7f3759986800] DEBUG - Updating part with ID=501829 [/unionfs/TV/Better Call Saul/Season 1/Better Call Saul - 1x02.1.mkv]
Dec 12, 2018 02:01:24.206 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:24.206 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=analyzing
Dec 12, 2018 02:01:24.206 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:24.207 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=thumbnailing
Dec 12, 2018 02:01:24.207 [0x7f3748fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=idle
Dec 12, 2018 02:01:24.208 [0x7f3748fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=56407&metadataType=4&state=-1&mediaState=idle
