Jan 08, 2019 04:08:01.759 [0x7f2842ed6700] INFO - Plex Media Scanner v1.14.1.5488-cc260c476 - ubuntu PC x86_64 - build: linux-ubuntu-x86_64 - GMT -07:00
Jan 08, 2019 04:08:01.760 [0x7f2842ed6700] INFO - Linux version: 3.10.0-957.1.3.el7.x86_64 (#1 SMP Thu Nov 29 14:49:43 UTC 2018), language: en-US
Jan 08, 2019 04:08:01.760 [0x7f2842ed6700] INFO - Processor Intel(R) Xeon(R) CPU           E5645  @ 2.40GHz
Jan 08, 2019 04:08:01.760 [0x7f2842ed6700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 8456,8645,9082,8644,9738,8762,10050,9205,9202,9786 --log-file-suffix  Analysis
Jan 08, 2019 04:08:01.768 [0x7f2854b33840] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Jan 08, 2019 04:08:01.973 [0x7f2854b33840] DEBUG - It took 0.000000 ms to retrieve 126 items.
Jan 08, 2019 04:08:01.983 [0x7f2854b33840] DEBUG - Analyzing media parts for item 8456 (Cinderella): 8118
Jan 08, 2019 04:08:01.983 [0x7f2854b33840] DEBUG - [ID 20279] Media part analysis: /mnt/nfs/media/Videos/Movies/Cinderella (2015)/Cinderella (2015).mkv
Jan 08, 2019 04:08:01.983 [0x7f2854b33840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Cinderella (2015)/Cinderella (2015).mkv"
Jan 08, 2019 04:08:01.984 [0x7f2854b33840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Cinderella (2015)/Cinderella (2015).mkv' for reading
Jan 08, 2019 04:08:01.984 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:02.007 [0x7f2854b33840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:02.036 [0x7f2854b33840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:02.036 [0x7f2854b33840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:02.036 [0x7f2854b33840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 745 bytes read:62826 seeks:2 nb_streams:2
Jan 08, 2019 04:08:02.037 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:02.037 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:02.038 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:02.038 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:02.039 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:02.039 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:02.039 [0x7f2854b33840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:02.043 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:02.046 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:02.049 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:02.051 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:02.057 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:02.063 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:02.068 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:02.071 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:02.074 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:02.510 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:02.515 [0x7f2854b33840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:03.814 [0x7f2854b33840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 114431 bytes read:193898 seeks:2 frames:38
Jan 08, 2019 04:08:03.816 [0x7f2854b33840] DEBUG - [MI]  * 3f71397b14c1abe3f9dcb490137b5745a8c2623d [mkv h264/dca] Duration: 6322066ms, 1920x804 (2.388060) 23.976fps, bitrate=12870kbps, audio channels=8, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Cinderella (2015)/Cinderella (2015).mkv")
Jan 08, 2019 04:08:03.817 [0x7f2854b33840] DEBUG - [MI] Importing 18 media chapters.
Jan 08, 2019 04:08:03.818 [0x7f2854b33840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:03.823 [0x7f2854b33840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.823 [0x7f2854b33840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.827 [0x7f2854b33840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.828 [0x7f2854b33840] DEBUG - Updating metadata item (save) (Cinderella) with ID 8456
Jan 08, 2019 04:08:03.839 [0x7f2854b33840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.839 [0x7f2854b33840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.843 [0x7f2854b33840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.843 [0x7f2854b33840] DEBUG - Updating metadata item (save) (Cinderella) with ID 8456
Jan 08, 2019 04:08:03.846 [0x7f2854b33840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Cinderella (2015)/Cinderella (2015).mkv"
Jan 08, 2019 04:08:03.850 [0x7f2854b33840] DEBUG - [FFMPEG] - Statistics: 193898 bytes read, 2 seeks
Jan 08, 2019 04:08:03.852 [0x7f2854b33840] DEBUG - Updating media item 8118, size=10171026341, metadata_item_id=8456
Jan 08, 2019 04:08:03.853 [0x7f2854b33840] DEBUG - Updating part with ID=20279 [/mnt/nfs/media/Videos/Movies/Cinderella (2015)/Cinderella (2015).mkv]
Jan 08, 2019 04:08:03.855 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8456&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:03.857 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8456&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:03.857 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8456&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:03.859 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8456&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:03.859 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8456&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:03.860 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8456&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:03.867 [0x7f2854b33840] DEBUG - Analyzing media parts for item 8645 (Ice Age): 9831
Jan 08, 2019 04:08:03.867 [0x7f2854b33840] DEBUG - [ID 16848] Media part analysis: /mnt/nfs/media/Videos/Movies/Ice Age (2004).mkv
Jan 08, 2019 04:08:03.867 [0x7f2854b33840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Ice Age (2004).mkv"
Jan 08, 2019 04:08:03.867 [0x7f2854b33840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Ice Age (2004).mkv' for reading
Jan 08, 2019 04:08:03.867 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:03.899 [0x7f2854b33840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:03.905 [0x7f2854b33840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:03.905 [0x7f2854b33840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:03.905 [0x7f2854b33840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 733 bytes read:59658 seeks:2 nb_streams:2
Jan 08, 2019 04:08:03.905 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:03.905 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:03.906 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:03.906 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:03.907 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:03.907 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:03.907 [0x7f2854b33840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:03.908 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:03.909 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:03.910 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:03.910 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:03.912 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:03.912 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:03.913 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:03.914 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:03.916 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:03.916 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:03.917 [0x7f2854b33840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:03.918 [0x7f2854b33840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 18180 bytes read:92426 seeks:2 frames:19
Jan 08, 2019 04:08:03.918 [0x7f2854b33840] DEBUG - [MI]  * 1252b1d38d41e9a98379b3a524db2d15b2aada08 [mkv h264/ac3] Duration: 4867321ms, 720x464 (1.838348) 23.976fps, bitrate=1816kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Ice Age (2004).mkv")
Jan 08, 2019 04:08:03.919 [0x7f2854b33840] DEBUG - [MI] Importing 20 media chapters.
Jan 08, 2019 04:08:03.919 [0x7f2854b33840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:03.922 [0x7f2854b33840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.922 [0x7f2854b33840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.924 [0x7f2854b33840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.924 [0x7f2854b33840] DEBUG - Updating metadata item (save) (Ice Age) with ID 8645
Jan 08, 2019 04:08:03.931 [0x7f2854b33840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.931 [0x7f2854b33840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.934 [0x7f2854b33840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:03.934 [0x7f2854b33840] DEBUG - Updating metadata item (save) (Ice Age) with ID 8645
Jan 08, 2019 04:08:03.936 [0x7f2854b33840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Ice Age (2004).mkv"
Jan 08, 2019 04:08:03.938 [0x7f2854b33840] DEBUG - [FFMPEG] - Statistics: 92426 bytes read, 2 seeks
Jan 08, 2019 04:08:03.941 [0x7f2854b33840] DEBUG - Updating media item 9831, size=1105416394, metadata_item_id=8645
Jan 08, 2019 04:08:03.941 [0x7f2854b33840] DEBUG - Updating part with ID=16848 [/mnt/nfs/media/Videos/Movies/Ice Age (2004).mkv]
Jan 08, 2019 04:08:03.943 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8645&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:03.944 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8645&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:03.945 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8645&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:03.946 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8645&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:03.947 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8645&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:03.948 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8645&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:03.958 [0x7f2854b33840] DEBUG - Analyzing media parts for item 9082 (I Am Sam): 16582
Jan 08, 2019 04:08:03.959 [0x7f2854b33840] DEBUG - [ID 23610] Media part analysis: /mnt/nfs/media/Videos/Movies/I Am Sam (2001).mkv
Jan 08, 2019 04:08:03.959 [0x7f2854b33840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/I Am Sam (2001).mkv"
Jan 08, 2019 04:08:03.960 [0x7f2854b33840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/I Am Sam (2001).mkv' for reading
Jan 08, 2019 04:08:03.960 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:03.982 [0x7f2854b33840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:03.991 [0x7f2854b33840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:03.991 [0x7f2854b33840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:03.991 [0x7f2854b33840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:03.991 [0x7f2854b33840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:03.991 [0x7f2854b33840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 924 bytes read:65536 seeks:2 nb_streams:4
Jan 08, 2019 04:08:03.991 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:03.992 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:03.993 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:03.993 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:03.993 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:03.993 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:03.993 [0x7f2854b33840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:03.994 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:03.995 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:03.995 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:03.996 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:03.997 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:03.999 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:04.000 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:04.001 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:04.003 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:04.005 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:04.006 [0x7f2854b33840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:04.010 [0x7f2854b33840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 56873 bytes read:131072 seeks:2 frames:43
Jan 08, 2019 04:08:04.010 [0x7f2854b33840] DEBUG - [MI]  * b3dd3ee6a8ec6fb8ffd44454ab99f67ff97e187a [mkv h264/ac3] Duration: 7938363ms, 720x470 (1.814880) 29.970fps, bitrate=3284kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/I Am Sam (2001).mkv")
Jan 08, 2019 04:08:04.011 [0x7f2854b33840] DEBUG - [MI] Importing 21 media chapters.
Jan 08, 2019 04:08:04.011 [0x7f2854b33840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:04.015 [0x7f2854b33840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/I Am Sam (2001).mkv"
Jan 08, 2019 04:08:04.016 [0x7f2854b33840] DEBUG - [FFMPEG] - Statistics: 131072 bytes read, 2 seeks
Jan 08, 2019 04:08:04.019 [0x7f2854b33840] DEBUG - Updating media item 16582, size=3259156601, metadata_item_id=9082
Jan 08, 2019 04:08:04.019 [0x7f2854b33840] DEBUG - Updating part with ID=23610 [/mnt/nfs/media/Videos/Movies/I Am Sam (2001).mkv]
Jan 08, 2019 04:08:04.021 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9082&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:04.024 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9082&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:04.024 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9082&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:04.026 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9082&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:04.026 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9082&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:04.028 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9082&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:04.038 [0x7f2854b33840] DEBUG - Analyzing media parts for item 8644 (The Little Mermaid): 9542
Jan 08, 2019 04:08:04.038 [0x7f2854b33840] DEBUG - [ID 20277] Media part analysis: /mnt/nfs/media/Videos/Movies/The Little Mermaid (1989)/The Little Mermaid (1989) Platinum Edition (2006).mkv
Jan 08, 2019 04:08:04.038 [0x7f2854b33840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/The Little Mermaid (1989)/The Little Mermaid (1989) Platinum Edition (2006).mkv"
Jan 08, 2019 04:08:04.038 [0x7f2854b33840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/The Little Mermaid (1989)/The Little Mermaid (1989) Platinum Edition (2006).mkv' for reading
Jan 08, 2019 04:08:04.038 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:04.517 [0x7f2854b33840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:04.567 [0x7f2854b33840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:04.567 [0x7f2854b33840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:04.567 [0x7f2854b33840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 735 bytes read:64256 seeks:2 nb_streams:2
Jan 08, 2019 04:08:04.568 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:04.568 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:04.569 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:04.569 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:04.569 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:04.569 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:04.569 [0x7f2854b33840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:04.570 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:04.571 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:04.571 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:04.572 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:04.573 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:04.574 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:04.574 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:04.575 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:04.577 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:04.578 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:04.580 [0x7f2854b33840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:05.450 [0x7f2854b33840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 18164 bytes read:97024 seeks:2 frames:19
Jan 08, 2019 04:08:05.450 [0x7f2854b33840] DEBUG - [MI]  * 943a6f6755386539b43e10b6f66136602a1bcb9e [mkv h264/ac3] Duration: 4958662ms, 720x478 (1.784505) 23.976fps, bitrate=1876kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/The Little Mermaid (1989)/The Little Mermaid (1989) Platinum Edition (2006).mkv")
Jan 08, 2019 04:08:05.451 [0x7f2854b33840] DEBUG - [MI] Importing 27 media chapters.
Jan 08, 2019 04:08:05.452 [0x7f2854b33840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:05.456 [0x7f2854b33840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/The Little Mermaid (1989)/The Little Mermaid (1989) Platinum Edition (2006).mkv"
Jan 08, 2019 04:08:05.457 [0x7f2854b33840] DEBUG - [FFMPEG] - Statistics: 97024 bytes read, 2 seeks
Jan 08, 2019 04:08:05.460 [0x7f2854b33840] DEBUG - Updating media item 9542, size=1162896453, metadata_item_id=8644
Jan 08, 2019 04:08:05.460 [0x7f2854b33840] DEBUG - Updating part with ID=20277 [/mnt/nfs/media/Videos/Movies/The Little Mermaid (1989)/The Little Mermaid (1989) Platinum Edition (2006).mkv]
Jan 08, 2019 04:08:05.462 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8644&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:05.465 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8644&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:05.465 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8644&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:05.467 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8644&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:05.467 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8644&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:05.468 [0x7f2854b33840] DEBUG - Analyzing media parts for item 9738 (Dr. Pierce and Mr. Hyde): 21815
Jan 08, 2019 04:08:05.469 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8644&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:05.469 [0x7f2854b33840] DEBUG - [ID 29119] Media part analysis: /mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x05 - Dr. Pierce and Mr. Hyde.mkv
Jan 08, 2019 04:08:05.469 [0x7f2854b33840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x05 - Dr. Pierce and Mr. Hyde.mkv"
Jan 08, 2019 04:08:05.469 [0x7f2854b33840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x05 - Dr. Pierce and Mr. Hyde.mkv' for reading
Jan 08, 2019 04:08:05.470 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:05.515 [0x7f2854b33840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:05.543 [0x7f2854b33840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.543 [0x7f2854b33840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.544 [0x7f2854b33840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.544 [0x7f2854b33840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.544 [0x7f2854b33840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7038 bytes read:45952 seeks:2 nb_streams:4
Jan 08, 2019 04:08:05.544 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:05.544 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.545 [0x7f2854b33840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:05.546 [0x7f2854b33840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:05.547 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:05.547 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:05.547 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:05.547 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:05.547 [0x7f2854b33840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:05.548 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:05.549 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.550 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:05.551 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.552 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:05.553 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.554 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.555 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:05.556 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.557 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.557 [0x7f2854b33840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:05.558 [0x7f2854b33840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 8287 bytes read:78720 seeks:2 frames:34
Jan 08, 2019 04:08:05.558 [0x7f2854b33840] DEBUG - [MI]  * dd1595991b4c16b76a26905a2f5c3c07adf692ab [mkv h264/aac] Duration: 1560726ms, 640x480 (1.333333) 23.976fps, bitrate=941kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x05 - Dr. Pierce and Mr. Hyde.mkv")
Jan 08, 2019 04:08:05.559 [0x7f2854b33840] DEBUG - [MI] Importing 11 media chapters.
Jan 08, 2019 04:08:05.560 [0x7f2854b33840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:05.562 [0x7f2854b33840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x05 - Dr. Pierce and Mr. Hyde.mkv"
Jan 08, 2019 04:08:05.563 [0x7f2854b33840] DEBUG - [FFMPEG] - Statistics: 78720 bytes read, 2 seeks
Jan 08, 2019 04:08:05.564 [0x7f2854b33840] DEBUG - Committing media stream changes for part 29119
Jan 08, 2019 04:08:05.567 [0x7f2854b33840] DEBUG - Updating media item 21815, size=183753027, metadata_item_id=9738
Jan 08, 2019 04:08:05.567 [0x7f2854b33840] DEBUG - Updating part with ID=29119 [/mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x05 - Dr. Pierce and Mr. Hyde.mkv]
Jan 08, 2019 04:08:05.569 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9738&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:05.571 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9738&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:05.571 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9738&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:05.573 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9738&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:05.573 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9738&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:05.575 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9738&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:05.583 [0x7f2854b33840] DEBUG - Analyzing media parts for item 8762 (The Bourne Supremacy): 12023
Jan 08, 2019 04:08:05.583 [0x7f2854b33840] DEBUG - [ID 19040] Media part analysis: /mnt/nfs/media/Videos/Movies/The Bourne Supremacy (2004).mkv
Jan 08, 2019 04:08:05.584 [0x7f2854b33840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/The Bourne Supremacy (2004).mkv"
Jan 08, 2019 04:08:05.584 [0x7f2854b33840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/The Bourne Supremacy (2004).mkv' for reading
Jan 08, 2019 04:08:05.584 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:05.602 [0x7f2854b33840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:05.615 [0x7f2854b33840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.615 [0x7f2854b33840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.615 [0x7f2854b33840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 734 bytes read:65536 seeks:2 nb_streams:2
Jan 08, 2019 04:08:05.616 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:05.616 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:05.617 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:05.617 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:05.617 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:05.618 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:05.618 [0x7f2854b33840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:05.619 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:05.619 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.620 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:05.620 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:05.622 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:05.622 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.623 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.623 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:05.625 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:05.626 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.626 [0x7f2854b33840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:05.627 [0x7f2854b33840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 14536 bytes read:98304 seeks:2 frames:17
Jan 08, 2019 04:08:05.627 [0x7f2854b33840] DEBUG - [MI]  * 4a0453f57b3e937e9f510aec16b44d8ad74d3388 [mkv h264/ac3] Duration: 6512572ms, 720x362 (2.356336) 29.970fps, bitrate=1822kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/The Bourne Supremacy (2004).mkv")
Jan 08, 2019 04:08:05.628 [0x7f2854b33840] DEBUG - [MI] Importing 24 media chapters.
Jan 08, 2019 04:08:05.628 [0x7f2854b33840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:05.631 [0x7f2854b33840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:05.631 [0x7f2854b33840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:05.634 [0x7f2854b33840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:05.634 [0x7f2854b33840] DEBUG - Updating metadata item (save) (The Bourne Supremacy) with ID 8762
Jan 08, 2019 04:08:05.641 [0x7f2854b33840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:05.642 [0x7f2854b33840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:05.644 [0x7f2854b33840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:05.644 [0x7f2854b33840] DEBUG - Updating metadata item (save) (The Bourne Supremacy) with ID 8762
Jan 08, 2019 04:08:05.647 [0x7f2854b33840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/The Bourne Supremacy (2004).mkv"
Jan 08, 2019 04:08:05.648 [0x7f2854b33840] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Jan 08, 2019 04:08:05.652 [0x7f2854b33840] DEBUG - Updating media item 12023, size=1483719323, metadata_item_id=8762
Jan 08, 2019 04:08:05.652 [0x7f2854b33840] DEBUG - Updating part with ID=19040 [/mnt/nfs/media/Videos/Movies/The Bourne Supremacy (2004).mkv]
Jan 08, 2019 04:08:05.654 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8762&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:05.657 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8762&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:05.657 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8762&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:05.659 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8762&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:05.660 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8762&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:05.662 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8762&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:05.673 [0x7f2854b33840] DEBUG - Analyzing media parts for item 10050 (Hotel Transylvania 3: Summer Vacation): 22486
Jan 08, 2019 04:08:05.673 [0x7f2854b33840] DEBUG - [ID 29790] Media part analysis: /mnt/nfs/media/Videos/Movies/Hotel Transylvania 3 (2018)/Hotel Transylvania 3 (2018).mkv
Jan 08, 2019 04:08:05.673 [0x7f2854b33840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Hotel Transylvania 3 (2018)/Hotel Transylvania 3 (2018).mkv"
Jan 08, 2019 04:08:05.673 [0x7f2854b33840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Hotel Transylvania 3 (2018)/Hotel Transylvania 3 (2018).mkv' for reading
Jan 08, 2019 04:08:05.673 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:05.714 [0x7f2854b33840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:05.721 [0x7f2854b33840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.721 [0x7f2854b33840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.721 [0x7f2854b33840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.721 [0x7f2854b33840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.721 [0x7f2854b33840] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:05.721 [0x7f2854b33840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1040 bytes read:64843 seeks:2 nb_streams:5
Jan 08, 2019 04:08:05.721 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:05.721 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:05.723 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:05.723 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:05.723 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:05.723 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:05.723 [0x7f2854b33840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:05.728 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:05.733 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.736 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:05.739 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.748 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:05.757 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:05.766 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.771 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:05.776 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:05.782 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:05.787 [0x7f2854b33840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:05.788 [0x7f2854b33840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 181755 bytes read:261451 seeks:2 frames:86
Jan 08, 2019 04:08:05.788 [0x7f2854b33840] DEBUG - [MI]  * bbfeec14f816e699d6c70cd97e3cbe890d4ccf4a [mkv h264/dca] Duration: 5843328ms, 1920x1036 (1.853282) 23.976fps, bitrate=10788kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Hotel Transylvania 3 (2018)/Hotel Transylvania 3 (2018).mkv")
Jan 08, 2019 04:08:05.789 [0x7f2854b33840] DEBUG - [MI] Importing 16 media chapters.
Jan 08, 2019 04:08:05.790 [0x7f2854b33840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:05.793 [0x7f2854b33840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Hotel Transylvania 3 (2018)/Hotel Transylvania 3 (2018).mkv"
Jan 08, 2019 04:08:05.796 [0x7f2854b33840] DEBUG - [FFMPEG] - Statistics: 261451 bytes read, 2 seeks
Jan 08, 2019 04:08:05.798 [0x7f2854b33840] DEBUG - Updating media item 22486, size=7879736815, metadata_item_id=10050
Jan 08, 2019 04:08:05.799 [0x7f2854b33840] DEBUG - Updating part with ID=29790 [/mnt/nfs/media/Videos/Movies/Hotel Transylvania 3 (2018)/Hotel Transylvania 3 (2018).mkv]
Jan 08, 2019 04:08:05.801 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10050&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:05.804 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10050&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:05.804 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10050&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:05.806 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10050&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:05.806 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10050&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:05.808 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=10050&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:05.810 [0x7f2854b33840] DEBUG - Analyzing media parts for item 9205 (A Date with Jennifer): 18682
Jan 08, 2019 04:08:05.812 [0x7f2854b33840] DEBUG - [ID 25814] Media part analysis: /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E10 - A Date with Jennifer.mp4
Jan 08, 2019 04:08:05.812 [0x7f2854b33840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E10 - A Date with Jennifer.mp4"
Jan 08, 2019 04:08:05.812 [0x7f2854b33840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E10 - A Date with Jennifer.mp4' for reading
Jan 08, 2019 04:08:05.812 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:06.721 [0x7f2854b33840] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Jan 08, 2019 04:08:06.721 [0x7f2854b33840] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Jan 08, 2019 04:08:06.751 [0x7f2854b33840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:07.646 [0x7f2854b33840] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 6006, duration: 135768633
Jan 08, 2019 04:08:07.651 [0x7f2854b33840] DEBUG - [FFMPEG] - Offset DTS by 6006 to make first pts zero.
Jan 08, 2019 04:08:07.661 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Jan 08, 2019 04:08:07.661 [0x7f2854b33840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:07.666 [0x7f2854b33840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:07.669 [0x7f2854b33840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:07.669 [0x7f2854b33840] DEBUG - [FFMPEG] - Processing st: 3, edit list 0 - media time: 6006, duration: 135768633
Jan 08, 2019 04:08:07.669 [0x7f2854b33840] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Jan 08, 2019 04:08:07.764 [0x7f2854b33840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 401477681 bytes read:1277647 seeks:8 nb_streams:4
Jan 08, 2019 04:08:07.764 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:07.764 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:07.765 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:07.765 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:07.765 [0x7f2854b33840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:07.766 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:07.767 [0x7f2854b33840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:07.770 [0x7f2854b33840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 6842 bytes read:1310415 seeks:9 frames:13
Jan 08, 2019 04:08:07.770 [0x7f2854b33840] DEBUG - [MI] Opening "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E10 - A Date with Jennifer.mp4" for MP4 atom parsing
Jan 08, 2019 04:08:07.770 [0x7f2854b33840] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Jan 08, 2019 04:08:07.770 [0x7f2854b33840] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Jan 08, 2019 04:08:07.770 [0x7f2854b33840] DEBUG - Mp4AtomParser: Skipping mdat atom of size 400362739 at offset 160
Jan 08, 2019 04:08:07.771 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found moov atom of size 1114758 at offset 400362899
Jan 08, 2019 04:08:07.771 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found trak atom of size 673985 at offset 400363039
Jan 08, 2019 04:08:07.771 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found trak atom of size 364098 at offset 401037024
Jan 08, 2019 04:08:07.771 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found trak atom of size 75852 at offset 401401122
Jan 08, 2019 04:08:07.771 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found trak atom of size 572 at offset 401476974
Jan 08, 2019 04:08:07.771 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found mdia atom of size 673829 at offset 400363139
Jan 08, 2019 04:08:07.771 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found minf atom of size 673756 at offset 400363212
Jan 08, 2019 04:08:07.771 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found stbl atom of size 673692 at offset 400363276
Jan 08, 2019 04:08:07.771 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found co64 atom of size 90440 at offset 400544405
Jan 08, 2019 04:08:07.771 [0x7f2854b33840] DEBUG - [MI] File has co64 atom with size 90440 at offset 400544405
Jan 08, 2019 04:08:07.772 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 401036968
Jan 08, 2019 04:08:07.772 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 401036976
Jan 08, 2019 04:08:07.772 [0x7f2854b33840] DEBUG - [MI] Determined stream id 4 has chapters, not subtitles
Jan 08, 2019 04:08:07.772 [0x7f2854b33840] DEBUG - [MI]  * a7e8ae6689942e19f6c6014636dc240197a4fe88 [mp4 h264/aac] Duration: 1508540ms, 706x480 (1.307407) 29.970fps, bitrate=2129kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E10 - A Date with Jennifer.mp4")
Jan 08, 2019 04:08:07.773 [0x7f2854b33840] DEBUG - [MI] Importing 5 media chapters.
Jan 08, 2019 04:08:07.773 [0x7f2854b33840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:07.775 [0x7f2854b33840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E10 - A Date with Jennifer.mp4"
Jan 08, 2019 04:08:07.776 [0x7f2854b33840] DEBUG - [FFMPEG] - Statistics: 1310415 bytes read, 9 seeks
Jan 08, 2019 04:08:07.778 [0x7f2854b33840] DEBUG - Updating media item 18682, size=401477789, metadata_item_id=9205
Jan 08, 2019 04:08:07.778 [0x7f2854b33840] DEBUG - Updating part with ID=25814 [/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E10 - A Date with Jennifer.mp4]
Jan 08, 2019 04:08:07.781 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9205&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:07.783 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9205&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:07.784 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9205&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:07.785 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9205&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:07.786 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9205&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:07.787 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9205&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:07.788 [0x7f2854b33840] DEBUG - Analyzing media parts for item 9202 (Turkeys Away): 18679
Jan 08, 2019 04:08:07.790 [0x7f2854b33840] DEBUG - [ID 25811] Media part analysis: /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E07 - Turkeys Away.mp4
Jan 08, 2019 04:08:07.790 [0x7f2854b33840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E07 - Turkeys Away.mp4"
Jan 08, 2019 04:08:07.790 [0x7f2854b33840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E07 - Turkeys Away.mp4' for reading
Jan 08, 2019 04:08:07.790 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:07.794 [0x7f2854b33840] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Jan 08, 2019 04:08:07.794 [0x7f2854b33840] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Jan 08, 2019 04:08:07.803 [0x7f2854b33840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:08.277 [0x7f2854b33840] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 6006, duration: 134789655
Jan 08, 2019 04:08:08.281 [0x7f2854b33840] DEBUG - [FFMPEG] - Offset DTS by 6006 to make first pts zero.
Jan 08, 2019 04:08:08.291 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Jan 08, 2019 04:08:08.292 [0x7f2854b33840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:08.297 [0x7f2854b33840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:08.300 [0x7f2854b33840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:08.300 [0x7f2854b33840] DEBUG - [FFMPEG] - Processing st: 3, edit list 0 - media time: 6006, duration: 134789655
Jan 08, 2019 04:08:08.300 [0x7f2854b33840] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Jan 08, 2019 04:08:08.367 [0x7f2854b33840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 304428029 bytes read:1240359 seeks:7 nb_streams:4
Jan 08, 2019 04:08:08.367 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:08.367 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:08.368 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:08.368 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:08.368 [0x7f2854b33840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:08.369 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:08.370 [0x7f2854b33840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:08.374 [0x7f2854b33840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 9940 bytes read:1273127 seeks:8 frames:13
Jan 08, 2019 04:08:08.374 [0x7f2854b33840] DEBUG - [MI] Opening "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E07 - Turkeys Away.mp4" for MP4 atom parsing
Jan 08, 2019 04:08:08.374 [0x7f2854b33840] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Jan 08, 2019 04:08:08.374 [0x7f2854b33840] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Jan 08, 2019 04:08:08.374 [0x7f2854b33840] DEBUG - Mp4AtomParser: Skipping mdat atom of size 303322981 at offset 160
Jan 08, 2019 04:08:08.374 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found moov atom of size 1104864 at offset 303323141
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found trak atom of size 667307 at offset 303323281
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found trak atom of size 361462 at offset 303990588
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found trak atom of size 75288 at offset 304352050
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found trak atom of size 556 at offset 304427338
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found mdia atom of size 667151 at offset 303323381
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found minf atom of size 667078 at offset 303323454
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found stbl atom of size 667014 at offset 303323518
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found co64 atom of size 89792 at offset 303503343
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - [MI] File has co64 atom with size 89792 at offset 303503343
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 303990532
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 303990540
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - [MI] Determined stream id 4 has chapters, not subtitles
Jan 08, 2019 04:08:08.375 [0x7f2854b33840] DEBUG - [MI]  * 446a3aeaf993f8ee63f786650e4dd2b00d9162fa [mp4 h264/aac] Duration: 1497663ms, 700x480 (1.296296) 29.970fps, bitrate=1626kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E07 - Turkeys Away.mp4")
Jan 08, 2019 04:08:08.376 [0x7f2854b33840] DEBUG - [MI] Importing 4 media chapters.
Jan 08, 2019 04:08:08.376 [0x7f2854b33840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:08.378 [0x7f2854b33840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E07 - Turkeys Away.mp4"
Jan 08, 2019 04:08:08.379 [0x7f2854b33840] DEBUG - [FFMPEG] - Statistics: 1273127 bytes read, 8 seeks
Jan 08, 2019 04:08:08.381 [0x7f2854b33840] DEBUG - Updating media item 18679, size=304428137, metadata_item_id=9202
Jan 08, 2019 04:08:08.382 [0x7f2854b33840] DEBUG - Updating part with ID=25811 [/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01/WKRP In Cincinnati - S01E07 - Turkeys Away.mp4]
Jan 08, 2019 04:08:08.383 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9202&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:08.385 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9202&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:08.386 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9202&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:08.387 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9202&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:08.387 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9202&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:08.388 [0x7f2854b33840] DEBUG - Analyzing media parts for item 9786 (Germ Warfare): 21867
Jan 08, 2019 04:08:08.389 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9202&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:08.390 [0x7f2854b33840] DEBUG - [ID 29171] Media part analysis: /mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x11 - Germ Warfare.mkv
Jan 08, 2019 04:08:08.390 [0x7f2854b33840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x11 - Germ Warfare.mkv"
Jan 08, 2019 04:08:08.390 [0x7f2854b33840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x11 - Germ Warfare.mkv' for reading
Jan 08, 2019 04:08:08.390 [0x7f2854b33840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:08.419 [0x7f2854b33840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7031 bytes read:45744 seeks:2 nb_streams:4
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.437 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x2c2c2c
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x2c2c2c
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:08.438 [0x7f2854b33840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:08.439 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:08.439 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:08.439 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:08.439 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:08.439 [0x7f2854b33840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:08.440 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:08.441 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:08.442 [0x7f2854b33840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:08.442 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:08.444 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:08.445 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:08.447 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:08.447 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:08.448 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:08.449 [0x7f2854b33840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:08.451 [0x7f2854b33840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:08.909 [0x7f2854b33840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 18109 bytes read:78512 seeks:2 frames:34
Jan 08, 2019 04:08:08.909 [0x7f2854b33840] DEBUG - [MI]  * a5d20bbb3d4594240799cb6d4c2b82d366bc87bf [mkv h264/aac] Duration: 1537119ms, 640x480 (1.333333) 23.976fps, bitrate=956kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x11 - Germ Warfare.mkv")
Jan 08, 2019 04:08:08.910 [0x7f2854b33840] DEBUG - [MI] Importing 11 media chapters.
Jan 08, 2019 04:08:08.910 [0x7f2854b33840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:08.913 [0x7f2854b33840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x11 - Germ Warfare.mkv"
Jan 08, 2019 04:08:08.914 [0x7f2854b33840] DEBUG - [FFMPEG] - Statistics: 78512 bytes read, 2 seeks
Jan 08, 2019 04:08:08.916 [0x7f2854b33840] DEBUG - Committing media stream changes for part 29171
Jan 08, 2019 04:08:08.918 [0x7f2854b33840] DEBUG - Updating media item 21867, size=183797294, metadata_item_id=9786
Jan 08, 2019 04:08:08.918 [0x7f2854b33840] DEBUG - Updating part with ID=29171 [/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x11 - Germ Warfare.mkv]
Jan 08, 2019 04:08:08.920 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9786&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:08.922 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9786&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:08.922 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9786&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:08.924 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9786&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:08.924 [0x7f283d3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9786&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:08.926 [0x7f283d3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9786&metadataType=4&state=-1&mediaState=idle
