Jan 08, 2019 04:08:28.121 [0x7fc25b2d6700] 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:28.121 [0x7fc25b2d6700] 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:28.121 [0x7fc25b2d6700] INFO - Processor Intel(R) Xeon(R) CPU           E5645  @ 2.40GHz
Jan 08, 2019 04:08:28.121 [0x7fc25b2d6700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 9142,9788,9025,8699,9062,9735,10120,9179,9090,8696 --log-file-suffix  Analysis
Jan 08, 2019 04:08:28.130 [0x7fc26cd57840] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Jan 08, 2019 04:08:28.332 [0x7fc26cd57840] DEBUG - Analyzing media parts for item 9142 (Fire): 18622
Jan 08, 2019 04:08:28.333 [0x7fc26cd57840] DEBUG - [ID 25754] Media part analysis: /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E17 - Fire.mp4
Jan 08, 2019 04:08:28.334 [0x7fc26cd57840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E17 - Fire.mp4"
Jan 08, 2019 04:08:28.334 [0x7fc26cd57840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E17 - Fire.mp4' for reading
Jan 08, 2019 04:08:28.334 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:28.376 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Jan 08, 2019 04:08:28.376 [0x7fc26cd57840] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Jan 08, 2019 04:08:28.387 [0x7fc26cd57840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:28.440 [0x7fc26cd57840] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 6006, duration: 133792659
Jan 08, 2019 04:08:28.443 [0x7fc26cd57840] DEBUG - [FFMPEG] - Offset DTS by 6006 to make first pts zero.
Jan 08, 2019 04:08:28.454 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Jan 08, 2019 04:08:28.454 [0x7fc26cd57840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:28.458 [0x7fc26cd57840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:28.458 [0x7fc26cd57840] DEBUG - [FFMPEG] - Processing st: 2, edit list 0 - media time: 6006, duration: 133792659
Jan 08, 2019 04:08:28.458 [0x7fc26cd57840] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Jan 08, 2019 04:08:28.486 [0x7fc26cd57840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 273332507 bytes read:1088484 seeks:7 nb_streams:3
Jan 08, 2019 04:08:28.487 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:28.487 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:28.488 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:28.488 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:28.488 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:28.489 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:28.489 [0x7fc26cd57840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:28.490 [0x7fc26cd57840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3700 bytes read:1121252 seeks:8 frames:8
Jan 08, 2019 04:08:28.490 [0x7fc26cd57840] DEBUG - [MI] Opening "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E17 - Fire.mp4" for MP4 atom parsing
Jan 08, 2019 04:08:28.491 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Jan 08, 2019 04:08:28.491 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Jan 08, 2019 04:08:28.491 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Skipping mdat atom of size 272387718 at offset 160
Jan 08, 2019 04:08:28.491 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found moov atom of size 944605 at offset 272387878
Jan 08, 2019 04:08:28.491 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found trak atom of size 624824 at offset 272388018
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found trak atom of size 318990 at offset 273012842
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found trak atom of size 540 at offset 273331832
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found mdia atom of size 624668 at offset 272388118
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found minf atom of size 624595 at offset 272388191
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found stbl atom of size 624531 at offset 272388255
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 273012786
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 273012794
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found mdia atom of size 318868 at offset 273012942
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found minf atom of size 318795 at offset 273013015
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found stbl atom of size 318735 at offset 273013075
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found mdia atom of size 404 at offset 273331932
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found minf atom of size 331 at offset 273332005
Jan 08, 2019 04:08:28.492 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found stbl atom of size 211 at offset 273332125
Jan 08, 2019 04:08:28.493 [0x7fc26cd57840] DEBUG - [MI]  * 45cf45c3dc76caf28b731d2e2a6167a043ce408f [mp4 h264/aac] Duration: 1486585ms, 720x480 (1.337500) 29.970fps, bitrate=1470kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E17 - Fire.mp4")
Jan 08, 2019 04:08:28.494 [0x7fc26cd57840] DEBUG - [MI] Importing 4 media chapters.
Jan 08, 2019 04:08:28.495 [0x7fc26cd57840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:28.496 [0x7fc26cd57840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E17 - Fire.mp4"
Jan 08, 2019 04:08:28.497 [0x7fc26cd57840] DEBUG - [FFMPEG] - Statistics: 1121252 bytes read, 8 seeks
Jan 08, 2019 04:08:28.500 [0x7fc26cd57840] DEBUG - Updating media item 18622, size=273332615, metadata_item_id=9142
Jan 08, 2019 04:08:28.500 [0x7fc26cd57840] DEBUG - Updating part with ID=25754 [/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04/WKRP in Cincinnati - S04E17 - Fire.mp4]
Jan 08, 2019 04:08:28.502 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9142&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:28.506 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9142&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:28.506 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9142&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:28.507 [0x7fc26cd57840] DEBUG - Analyzing media parts for item 9788 (Edwina): 21869
Jan 08, 2019 04:08:28.508 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9142&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:28.508 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9142&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:28.509 [0x7fc26cd57840] DEBUG - [ID 29173] Media part analysis: /mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x13 - Edwina.mkv
Jan 08, 2019 04:08:28.509 [0x7fc26cd57840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x13 - Edwina.mkv"
Jan 08, 2019 04:08:28.509 [0x7fc26cd57840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x13 - Edwina.mkv' for reading
Jan 08, 2019 04:08:28.509 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:28.510 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9142&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:28.997 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:29.011 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:29.011 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:29.012 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:29.012 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:29.012 [0x7fc26cd57840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7031 bytes read:45746 seeks:2 nb_streams:4
Jan 08, 2019 04:08:29.012 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:29.012 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:29.012 [0x7fc26cd57840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:29.012 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.012 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x2c2c2c
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x2c2c2c
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:29.013 [0x7fc26cd57840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:29.014 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:29.014 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:29.014 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:29.014 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:29.014 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:29.015 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:29.017 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.017 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:29.018 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.019 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:29.020 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:29.022 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.022 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.023 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:29.025 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:29.026 [0x7fc26cd57840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:29.026 [0x7fc26cd57840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 8272 bytes read:78514 seeks:2 frames:34
Jan 08, 2019 04:08:29.027 [0x7fc26cd57840] DEBUG - [MI]  * 6170be65016cccdc9d3951922bbac0c2fc5a3185 [mkv h264/aac] Duration: 1537877ms, 640x480 (1.333333) 23.976fps, bitrate=955kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x13 - Edwina.mkv")
Jan 08, 2019 04:08:29.028 [0x7fc26cd57840] DEBUG - [MI] Importing 11 media chapters.
Jan 08, 2019 04:08:29.028 [0x7fc26cd57840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:29.030 [0x7fc26cd57840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x13 - Edwina.mkv"
Jan 08, 2019 04:08:29.031 [0x7fc26cd57840] DEBUG - [FFMPEG] - Statistics: 78514 bytes read, 2 seeks
Jan 08, 2019 04:08:29.033 [0x7fc26cd57840] DEBUG - Committing media stream changes for part 29173
Jan 08, 2019 04:08:29.035 [0x7fc26cd57840] DEBUG - Updating media item 21869, size=183727054, metadata_item_id=9788
Jan 08, 2019 04:08:29.036 [0x7fc26cd57840] DEBUG - Updating part with ID=29173 [/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x13 - Edwina.mkv]
Jan 08, 2019 04:08:29.037 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9788&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:29.039 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9788&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:29.040 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9788&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:29.042 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9788&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:29.042 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9788&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:29.044 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9788&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:29.052 [0x7fc26cd57840] DEBUG - Analyzing media parts for item 9025 (The Ghost and the Darkness): 16026
Jan 08, 2019 04:08:29.053 [0x7fc26cd57840] DEBUG - [ID 23054] Media part analysis: /mnt/nfs/media/Videos/Movies/The Ghost and the Darkness (1996).mkv
Jan 08, 2019 04:08:29.053 [0x7fc26cd57840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/The Ghost and the Darkness (1996).mkv"
Jan 08, 2019 04:08:29.053 [0x7fc26cd57840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/The Ghost and the Darkness (1996).mkv' for reading
Jan 08, 2019 04:08:29.053 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:29.086 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:29.099 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:29.099 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:29.099 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:29.099 [0x7fc26cd57840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 829 bytes read:65536 seeks:2 nb_streams:3
Jan 08, 2019 04:08:29.099 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:29.099 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:29.100 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:29.100 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:29.101 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:29.101 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:29.101 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:29.102 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:29.103 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.103 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:29.103 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:29.104 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.105 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.105 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:29.106 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:29.107 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.108 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.108 [0x7fc26cd57840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:29.109 [0x7fc26cd57840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 27765 bytes read:98304 seeks:2 frames:30
Jan 08, 2019 04:08:29.109 [0x7fc26cd57840] DEBUG - [MI]  * 3414e641748df2baf6d899c28d823e6af74b350c [mkv h264/ac3] Duration: 6595968ms, 718x306 (2.084655) 23.976fps, bitrate=3476kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/The Ghost and the Darkness (1996).mkv")
Jan 08, 2019 04:08:29.110 [0x7fc26cd57840] DEBUG - [MI] Importing 15 media chapters.
Jan 08, 2019 04:08:29.110 [0x7fc26cd57840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:29.113 [0x7fc26cd57840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/The Ghost and the Darkness (1996).mkv"
Jan 08, 2019 04:08:29.114 [0x7fc26cd57840] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Jan 08, 2019 04:08:29.116 [0x7fc26cd57840] DEBUG - Updating media item 16026, size=2866045904, metadata_item_id=9025
Jan 08, 2019 04:08:29.117 [0x7fc26cd57840] DEBUG - Updating part with ID=23054 [/mnt/nfs/media/Videos/Movies/The Ghost and the Darkness (1996).mkv]
Jan 08, 2019 04:08:29.118 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9025&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:29.120 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9025&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:29.120 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9025&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:29.122 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9025&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:29.122 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9025&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:29.123 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9025&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:29.134 [0x7fc26cd57840] DEBUG - Analyzing media parts for item 8699 (The Hunt for Red October): 10199
Jan 08, 2019 04:08:29.135 [0x7fc26cd57840] DEBUG - [ID 17216] Media part analysis: /mnt/nfs/media/Videos/Movies/The Hunt for Red October (1990).mkv
Jan 08, 2019 04:08:29.135 [0x7fc26cd57840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/The Hunt for Red October (1990).mkv"
Jan 08, 2019 04:08:29.135 [0x7fc26cd57840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/The Hunt for Red October (1990).mkv' for reading
Jan 08, 2019 04:08:29.135 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:29.155 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:29.175 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:29.175 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:29.175 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:29.175 [0x7fc26cd57840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 829 bytes read:65002 seeks:2 nb_streams:3
Jan 08, 2019 04:08:29.175 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:29.175 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:29.176 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:29.177 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:29.177 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:29.177 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:29.177 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:29.178 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:29.178 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.178 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:29.179 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:29.179 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.180 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.180 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:29.181 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:29.182 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.182 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:29.183 [0x7fc26cd57840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:29.183 [0x7fc26cd57840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 27744 bytes read:97770 seeks:2 frames:30
Jan 08, 2019 04:08:29.184 [0x7fc26cd57840] DEBUG - [MI]  * 2120f0bdbc8bec78da99f82e8a4c661388ed8af3 [mkv h264/ac3] Duration: 8108960ms, 718x282 (2.262072) 23.976fps, bitrate=3406kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/The Hunt for Red October (1990).mkv")
Jan 08, 2019 04:08:29.185 [0x7fc26cd57840] DEBUG - [MI] Importing 13 media chapters.
Jan 08, 2019 04:08:29.185 [0x7fc26cd57840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:29.187 [0x7fc26cd57840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:29.187 [0x7fc26cd57840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:29.190 [0x7fc26cd57840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:29.191 [0x7fc26cd57840] DEBUG - Updating metadata item (save) (The Hunt for Red October) with ID 8699
Jan 08, 2019 04:08:29.198 [0x7fc26cd57840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:29.199 [0x7fc26cd57840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:29.202 [0x7fc26cd57840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:29.202 [0x7fc26cd57840] DEBUG - Updating metadata item (save) (The Hunt for Red October) with ID 8699
Jan 08, 2019 04:08:29.205 [0x7fc26cd57840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/The Hunt for Red October (1990).mkv"
Jan 08, 2019 04:08:29.206 [0x7fc26cd57840] DEBUG - [FFMPEG] - Statistics: 97770 bytes read, 2 seeks
Jan 08, 2019 04:08:29.209 [0x7fc26cd57840] DEBUG - Updating media item 10199, size=3452903994, metadata_item_id=8699
Jan 08, 2019 04:08:29.209 [0x7fc26cd57840] DEBUG - Updating part with ID=17216 [/mnt/nfs/media/Videos/Movies/The Hunt for Red October (1990).mkv]
Jan 08, 2019 04:08:29.211 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8699&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:29.213 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8699&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:29.214 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8699&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:29.215 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8699&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:29.216 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8699&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:29.218 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8699&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:29.224 [0x7fc26cd57840] DEBUG - It took 10.000000 ms to retrieve 110 items.
Jan 08, 2019 04:08:29.229 [0x7fc26cd57840] DEBUG - Analyzing media parts for item 9062 (Rain Man): 16560
Jan 08, 2019 04:08:29.229 [0x7fc26cd57840] DEBUG - [ID 23588] Media part analysis: /mnt/nfs/media/Videos/Movies/Rain Man (1988).mkv
Jan 08, 2019 04:08:29.229 [0x7fc26cd57840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Rain Man (1988).mkv"
Jan 08, 2019 04:08:29.229 [0x7fc26cd57840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Rain Man (1988).mkv' for reading
Jan 08, 2019 04:08:29.229 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:30.101 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:30.104 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.105 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.105 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.105 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.105 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.105 [0x7fc26cd57840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 1016 bytes read:63537 seeks:2 nb_streams:5
Jan 08, 2019 04:08:30.105 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.105 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.108 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.108 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.108 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:30.109 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:30.109 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:30.110 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.110 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.111 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.111 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.113 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.114 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:30.115 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.115 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.116 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.117 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.118 [0x7fc26cd57840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:30.119 [0x7fc26cd57840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 39401 bytes read:129073 seeks:2 frames:46
Jan 08, 2019 04:08:30.119 [0x7fc26cd57840] DEBUG - [MI]  * 687a06aebaf4afc5673847ecad1c33e534eb7538 [mkv h264/ac3] Duration: 8029105ms, 718x462 (1.839795) 23.976fps, bitrate=2408kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Rain Man (1988).mkv")
Jan 08, 2019 04:08:30.120 [0x7fc26cd57840] DEBUG - [MI] Importing 32 media chapters.
Jan 08, 2019 04:08:30.121 [0x7fc26cd57840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:30.129 [0x7fc26cd57840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Rain Man (1988).mkv"
Jan 08, 2019 04:08:30.130 [0x7fc26cd57840] DEBUG - [FFMPEG] - Statistics: 129073 bytes read, 2 seeks
Jan 08, 2019 04:08:30.133 [0x7fc26cd57840] DEBUG - Updating media item 16560, size=2417075664, metadata_item_id=9062
Jan 08, 2019 04:08:30.133 [0x7fc26cd57840] DEBUG - Updating part with ID=23588 [/mnt/nfs/media/Videos/Movies/Rain Man (1988).mkv]
Jan 08, 2019 04:08:30.135 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9062&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.137 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9062&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.138 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9062&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.140 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9062&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.141 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9062&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.141 [0x7fc26cd57840] DEBUG - Analyzing media parts for item 9735 (5 O'Clock Charlie): 21812
Jan 08, 2019 04:08:30.142 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9062&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.142 [0x7fc26cd57840] DEBUG - [ID 29116] Media part analysis: /mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x02 - 5 O'Clock Charlie.mkv
Jan 08, 2019 04:08:30.143 [0x7fc26cd57840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x02 - 5 O'Clock Charlie.mkv"
Jan 08, 2019 04:08:30.143 [0x7fc26cd57840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x02 - 5 O'Clock Charlie.mkv' for reading
Jan 08, 2019 04:08:30.143 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:30.187 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:30.194 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.194 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.194 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.194 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7037 bytes read:45965 seeks:2 nb_streams:4
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.195 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.196 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.197 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.197 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:30.197 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:30.197 [0x7fc26cd57840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:30.197 [0x7fc26cd57840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:30.197 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.197 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.198 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:30.198 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:30.198 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:30.198 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.199 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.200 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.200 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.202 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.203 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.204 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.205 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.206 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.207 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.208 [0x7fc26cd57840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:30.208 [0x7fc26cd57840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 8286 bytes read:78733 seeks:2 frames:34
Jan 08, 2019 04:08:30.209 [0x7fc26cd57840] DEBUG - [MI]  * bf74053a564d087ea67e3f1f044c7837bc8bbfca [mkv h264/aac] Duration: 1563008ms, 640x480 (1.333333) 23.976fps, bitrate=940kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x02 - 5 O'Clock Charlie.mkv")
Jan 08, 2019 04:08:30.209 [0x7fc26cd57840] DEBUG - [MI] Importing 11 media chapters.
Jan 08, 2019 04:08:30.210 [0x7fc26cd57840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:30.212 [0x7fc26cd57840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x02 - 5 O'Clock Charlie.mkv"
Jan 08, 2019 04:08:30.213 [0x7fc26cd57840] DEBUG - [FFMPEG] - Statistics: 78733 bytes read, 2 seeks
Jan 08, 2019 04:08:30.215 [0x7fc26cd57840] DEBUG - Committing media stream changes for part 29116
Jan 08, 2019 04:08:30.217 [0x7fc26cd57840] DEBUG - Updating media item 21812, size=183728348, metadata_item_id=9735
Jan 08, 2019 04:08:30.218 [0x7fc26cd57840] DEBUG - Updating part with ID=29116 [/mnt/nfs/media/Videos/TV Shows/MASH/Season 2/2x02 - 5 O'Clock Charlie.mkv]
Jan 08, 2019 04:08:30.220 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9735&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.222 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9735&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.222 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9735&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.224 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9735&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.225 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9735&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.225 [0x7fc26cd57840] DEBUG - Analyzing media parts for item 10120 (Roxette - Crash! Boom! Live! '95): 22580
Jan 08, 2019 04:08:30.225 [0x7fc26cd57840] DEBUG - [ID 29884] Media part analysis: /mnt/nfs/media/Videos/Music Video/Roxette - Crash! Boom! Live! '95.mkv
Jan 08, 2019 04:08:30.226 [0x7fc26cd57840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Music Video/Roxette - Crash! Boom! Live! '95.mkv"
Jan 08, 2019 04:08:30.226 [0x7fc26cd57840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Music Video/Roxette - Crash! Boom! Live! '95.mkv' for reading
Jan 08, 2019 04:08:30.226 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:30.227 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9735&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.242 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:30.250 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.250 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.250 [0x7fc26cd57840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 733 bytes read:54321 seeks:2 nb_streams:2
Jan 08, 2019 04:08:30.250 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.250 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.251 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.251 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.252 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:30.252 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:30.252 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:30.253 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.253 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.254 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.255 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.257 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.259 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.260 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.261 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.262 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.263 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.263 [0x7fc26cd57840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:30.264 [0x7fc26cd57840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 33346 bytes read:87089 seeks:2 frames:18
Jan 08, 2019 04:08:30.264 [0x7fc26cd57840] DEBUG - [MI]  * a35edc305bcf74d54c6556a57cd3d65b0e81fe6f [mkv h264/ac3] Duration: 5195690ms, 506x472 (1.269056) 29.970fps, bitrate=1741kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Music Video/Roxette - Crash! Boom! Live! '95.mkv")
Jan 08, 2019 04:08:30.265 [0x7fc26cd57840] DEBUG - [MI] Importing 20 media chapters.
Jan 08, 2019 04:08:30.265 [0x7fc26cd57840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:30.269 [0x7fc26cd57840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Music Video/Roxette - Crash! Boom! Live! '95.mkv"
Jan 08, 2019 04:08:30.270 [0x7fc26cd57840] DEBUG - [FFMPEG] - Statistics: 87089 bytes read, 2 seeks
Jan 08, 2019 04:08:30.271 [0x7fc26cd57840] DEBUG - Updating media item 22580, size=1130806967, metadata_item_id=10120
Jan 08, 2019 04:08:30.272 [0x7fc26cd57840] DEBUG - Updating part with ID=29884 [/mnt/nfs/media/Videos/Music Video/Roxette - Crash! Boom! Live! '95.mkv]
Jan 08, 2019 04:08:30.274 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=10120&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.276 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=10120&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.276 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=10120&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.278 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=10120&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.279 [0x7fc26cd57840] DEBUG - Analyzing media parts for item 9179 (Baby, If You've Ever Wondered): 18657
Jan 08, 2019 04:08:30.279 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=10120&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.280 [0x7fc26cd57840] DEBUG - [ID 25789] Media part analysis: /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E09 - Baby, If You've Ever Wondered.mp4
Jan 08, 2019 04:08:30.280 [0x7fc26cd57840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E09 - Baby, If You've Ever Wondered.mp4"
Jan 08, 2019 04:08:30.280 [0x7fc26cd57840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E09 - Baby, If You've Ever Wondered.mp4' for reading
Jan 08, 2019 04:08:30.280 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:30.281 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=6&metadataItemID=10120&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.308 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Jan 08, 2019 04:08:30.309 [0x7fc26cd57840] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Jan 08, 2019 04:08:30.327 [0x7fc26cd57840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:30.362 [0x7fc26cd57840] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 6006, duration: 132933801
Jan 08, 2019 04:08:30.367 [0x7fc26cd57840] DEBUG - [FFMPEG] - Offset DTS by 6006 to make first pts zero.
Jan 08, 2019 04:08:30.377 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Jan 08, 2019 04:08:30.377 [0x7fc26cd57840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:30.382 [0x7fc26cd57840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:30.385 [0x7fc26cd57840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:30.385 [0x7fc26cd57840] DEBUG - [FFMPEG] - Processing st: 3, edit list 0 - media time: 6006, duration: 132933801
Jan 08, 2019 04:08:30.385 [0x7fc26cd57840] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Jan 08, 2019 04:08:30.431 [0x7fc26cd57840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 343072536 bytes read:1264699 seeks:8 nb_streams:4
Jan 08, 2019 04:08:30.432 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.432 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.433 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:30.433 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:30.433 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:30.434 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.437 [0x7fc26cd57840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:30.437 [0x7fc26cd57840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 12033 bytes read:1297467 seeks:9 frames:13
Jan 08, 2019 04:08:30.437 [0x7fc26cd57840] DEBUG - [MI] Opening "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E09 - Baby, If You've Ever Wondered.mp4" for MP4 atom parsing
Jan 08, 2019 04:08:30.437 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Jan 08, 2019 04:08:30.437 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Jan 08, 2019 04:08:30.437 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Skipping mdat atom of size 341983286 at offset 160
Jan 08, 2019 04:08:30.438 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found moov atom of size 1089066 at offset 341983446
Jan 08, 2019 04:08:30.438 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found trak atom of size 657465 at offset 341983586
Jan 08, 2019 04:08:30.438 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found trak atom of size 356502 at offset 342641051
Jan 08, 2019 04:08:30.438 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found trak atom of size 74276 at offset 342997553
Jan 08, 2019 04:08:30.438 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found trak atom of size 572 at offset 343071829
Jan 08, 2019 04:08:30.438 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found mdia atom of size 657309 at offset 341983686
Jan 08, 2019 04:08:30.439 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found minf atom of size 657236 at offset 341983759
Jan 08, 2019 04:08:30.439 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found stbl atom of size 657172 at offset 341983823
Jan 08, 2019 04:08:30.439 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found co64 atom of size 88552 at offset 342161176
Jan 08, 2019 04:08:30.439 [0x7fc26cd57840] DEBUG - [MI] File has co64 atom with size 88552 at offset 342161176
Jan 08, 2019 04:08:30.439 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 342640995
Jan 08, 2019 04:08:30.439 [0x7fc26cd57840] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 342641003
Jan 08, 2019 04:08:30.439 [0x7fc26cd57840] DEBUG - [MI] Determined stream id 4 has chapters, not subtitles
Jan 08, 2019 04:08:30.439 [0x7fc26cd57840] DEBUG - [MI]  * d2ea59af320ac2d7362c260de54e83f4bf75a08e [mp4 h264/aac] Duration: 1477042ms, 700x480 (1.296296) 29.970fps, bitrate=1858kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E09 - Baby, If You've Ever Wondered.mp4")
Jan 08, 2019 04:08:30.440 [0x7fc26cd57840] DEBUG - [MI] Importing 5 media chapters.
Jan 08, 2019 04:08:30.440 [0x7fc26cd57840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:30.442 [0x7fc26cd57840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E09 - Baby, If You've Ever Wondered.mp4"
Jan 08, 2019 04:08:30.443 [0x7fc26cd57840] DEBUG - [FFMPEG] - Statistics: 1297467 bytes read, 9 seeks
Jan 08, 2019 04:08:30.445 [0x7fc26cd57840] DEBUG - Updating media item 18657, size=343072644, metadata_item_id=9179
Jan 08, 2019 04:08:30.446 [0x7fc26cd57840] DEBUG - Updating part with ID=25789 [/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E09 - Baby, If You've Ever Wondered.mp4]
Jan 08, 2019 04:08:30.447 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9179&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.450 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9179&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.450 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9179&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.452 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9179&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.452 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9179&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.454 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9179&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.458 [0x7fc26cd57840] DEBUG - Analyzing media parts for item 9090 (Harley Davidson and the Marlboro Man): 16594
Jan 08, 2019 04:08:30.458 [0x7fc26cd57840] DEBUG - [ID 23622] Media part analysis: /mnt/nfs/media/Videos/Movies/Harley Davidson and the Marlboro Man (1991).mkv
Jan 08, 2019 04:08:30.458 [0x7fc26cd57840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Harley Davidson and the Marlboro Man (1991).mkv"
Jan 08, 2019 04:08:30.458 [0x7fc26cd57840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Harley Davidson and the Marlboro Man (1991).mkv' for reading
Jan 08, 2019 04:08:30.458 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:30.474 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:30.482 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.482 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.483 [0x7fc26cd57840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 732 bytes read:59730 seeks:2 nb_streams:2
Jan 08, 2019 04:08:30.483 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.483 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.484 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.484 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.485 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:30.485 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:30.485 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:30.486 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.487 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.488 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.488 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:30.490 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.490 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.491 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.493 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.494 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.495 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.496 [0x7fc26cd57840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:30.496 [0x7fc26cd57840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 10016 bytes read:92498 seeks:2 frames:20
Jan 08, 2019 04:08:30.497 [0x7fc26cd57840] DEBUG - [MI]  * 88aee0179b02cef900c4a2221728feef3723916c [mkv h264/ac3] Duration: 5903200ms, 720x468 (1.822636) 23.976fps, bitrate=2598kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Harley Davidson and the Marlboro Man (1991).mkv")
Jan 08, 2019 04:08:30.498 [0x7fc26cd57840] DEBUG - [MI] Importing 16 media chapters.
Jan 08, 2019 04:08:30.498 [0x7fc26cd57840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:30.501 [0x7fc26cd57840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Harley Davidson and the Marlboro Man (1991).mkv"
Jan 08, 2019 04:08:30.502 [0x7fc26cd57840] DEBUG - [FFMPEG] - Statistics: 92498 bytes read, 2 seeks
Jan 08, 2019 04:08:30.504 [0x7fc26cd57840] DEBUG - Updating media item 16594, size=1917631397, metadata_item_id=9090
Jan 08, 2019 04:08:30.504 [0x7fc26cd57840] DEBUG - Updating part with ID=23622 [/mnt/nfs/media/Videos/Movies/Harley Davidson and the Marlboro Man (1991).mkv]
Jan 08, 2019 04:08:30.506 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9090&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.508 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9090&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.509 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9090&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.510 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9090&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.511 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9090&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.512 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=9090&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.522 [0x7fc26cd57840] DEBUG - Analyzing media parts for item 8696 (The Hobbit: An Unexpected Journey): 10196
Jan 08, 2019 04:08:30.522 [0x7fc26cd57840] DEBUG - [ID 17213] Media part analysis: /mnt/nfs/media/Videos/Movies/The Hobbit An Unexpected Journey (2012).mkv
Jan 08, 2019 04:08:30.523 [0x7fc26cd57840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/The Hobbit An Unexpected Journey (2012).mkv"
Jan 08, 2019 04:08:30.523 [0x7fc26cd57840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/The Hobbit An Unexpected Journey (2012).mkv' for reading
Jan 08, 2019 04:08:30.523 [0x7fc26cd57840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:30.526 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:30.540 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.540 [0x7fc26cd57840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:30.540 [0x7fc26cd57840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 766 bytes read:65536 seeks:2 nb_streams:2
Jan 08, 2019 04:08:30.540 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.540 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.542 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:30.542 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:30.543 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:30.543 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:30.543 [0x7fc26cd57840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:30.546 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.548 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.550 [0x7fc26cd57840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:30.552 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.558 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.564 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.570 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.572 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:30.576 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.579 [0x7fc26cd57840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:30.583 [0x7fc26cd57840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:30.584 [0x7fc26cd57840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 62212 bytes read:131072 seeks:2 frames:38
Jan 08, 2019 04:08:30.584 [0x7fc26cd57840] DEBUG - [MI]  * 6281940f7bf49bcd24c2422b53138ef151c2f3c1 [mkv h264/dca] Duration: 10178176ms, 1920x800 (2.400000) 23.976fps, bitrate=13509kbps, audio channels=8, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/The Hobbit An Unexpected Journey (2012).mkv")
Jan 08, 2019 04:08:30.585 [0x7fc26cd57840] DEBUG - [MI] Importing 32 media chapters.
Jan 08, 2019 04:08:30.585 [0x7fc26cd57840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:30.588 [0x7fc26cd57840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:30.589 [0x7fc26cd57840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:30.592 [0x7fc26cd57840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:30.592 [0x7fc26cd57840] DEBUG - Updating metadata item (save) (The Hobbit: An Unexpected Journey) with ID 8696
Jan 08, 2019 04:08:30.602 [0x7fc26cd57840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:30.602 [0x7fc26cd57840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:30.605 [0x7fc26cd57840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:30.605 [0x7fc26cd57840] DEBUG - Updating metadata item (save) (The Hobbit: An Unexpected Journey) with ID 8696
Jan 08, 2019 04:08:30.607 [0x7fc26cd57840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/The Hobbit An Unexpected Journey (2012).mkv"
Jan 08, 2019 04:08:30.610 [0x7fc26cd57840] DEBUG - [FFMPEG] - Statistics: 131072 bytes read, 2 seeks
Jan 08, 2019 04:08:30.612 [0x7fc26cd57840] DEBUG - Updating media item 10196, size=17187475527, metadata_item_id=8696
Jan 08, 2019 04:08:30.613 [0x7fc26cd57840] DEBUG - Updating part with ID=17213 [/mnt/nfs/media/Videos/Movies/The Hobbit An Unexpected Journey (2012).mkv]
Jan 08, 2019 04:08:30.614 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8696&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.616 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8696&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:30.617 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8696&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.619 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8696&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:30.619 [0x7fc2567ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8696&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:30.621 [0x7fc2567ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8696&metadataType=1&state=-1&mediaState=idle
