Jan 08, 2019 04:08:09.263 [0x7f19522d6700] 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:09.263 [0x7f19522d6700] 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:09.263 [0x7f19522d6700] INFO - Processor Intel(R) Xeon(R) CPU           E5645  @ 2.40GHz
Jan 08, 2019 04:08:09.263 [0x7f19522d6700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 8754,8930,8620,8761,7944,9764,9185,8691,9153,8649 --log-file-suffix  Analysis
Jan 08, 2019 04:08:09.272 [0x7f1963f46840] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Jan 08, 2019 04:08:09.481 [0x7f1963f46840] DEBUG - Analyzing media parts for item 8754 (Chitty Chitty Bang Bang): 12015
Jan 08, 2019 04:08:09.481 [0x7f1963f46840] DEBUG - [ID 19032] Media part analysis: /mnt/nfs/media/Videos/Movies/Chitty Chitty Bang Bang (1968).mkv
Jan 08, 2019 04:08:09.481 [0x7f1963f46840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Chitty Chitty Bang Bang (1968).mkv"
Jan 08, 2019 04:08:09.482 [0x7f1963f46840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Chitty Chitty Bang Bang (1968).mkv' for reading
Jan 08, 2019 04:08:09.482 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:09.522 [0x7f1963f46840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:09.559 [0x7f1963f46840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:09.559 [0x7f1963f46840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:09.560 [0x7f1963f46840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 735 bytes read:65220 seeks:2 nb_streams:2
Jan 08, 2019 04:08:09.561 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:09.561 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:09.562 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:09.562 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:09.563 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:09.563 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:09.563 [0x7f1963f46840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:09.564 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:09.565 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:09.566 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:09.566 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:09.567 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:09.567 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:09.568 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:09.569 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:09.570 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:09.571 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:09.572 [0x7f1963f46840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:09.572 [0x7f1963f46840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 18146 bytes read:97988 seeks:2 frames:19
Jan 08, 2019 04:08:09.573 [0x7f1963f46840] DEBUG - [MI]  * 1e30df76bd3025254bae5f0833fa816e0b857dd0 [mkv h264/ac3] Duration: 8730145ms, 714x390 (2.169231) 23.976fps, bitrate=2418kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Chitty Chitty Bang Bang (1968).mkv")
Jan 08, 2019 04:08:09.574 [0x7f1963f46840] DEBUG - [MI] Importing 32 media chapters.
Jan 08, 2019 04:08:09.575 [0x7f1963f46840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:09.581 [0x7f1963f46840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Chitty Chitty Bang Bang (1968).mkv"
Jan 08, 2019 04:08:09.582 [0x7f1963f46840] DEBUG - [FFMPEG] - Statistics: 97988 bytes read, 2 seeks
Jan 08, 2019 04:08:09.584 [0x7f1963f46840] DEBUG - Updating media item 12015, size=2639550244, metadata_item_id=8754
Jan 08, 2019 04:08:09.585 [0x7f1963f46840] DEBUG - Updating part with ID=19032 [/mnt/nfs/media/Videos/Movies/Chitty Chitty Bang Bang (1968).mkv]
Jan 08, 2019 04:08:09.587 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8754&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:09.591 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8754&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:09.591 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8754&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:09.593 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8754&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:09.593 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8754&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:09.595 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8754&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:09.604 [0x7f1963f46840] DEBUG - Analyzing media parts for item 8930 (Top Gun): 15002
Jan 08, 2019 04:08:09.604 [0x7f1963f46840] DEBUG - [ID 22030] Media part analysis: /mnt/nfs/media/Videos/Movies/Top Gun (1986).mkv
Jan 08, 2019 04:08:09.604 [0x7f1963f46840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Top Gun (1986).mkv"
Jan 08, 2019 04:08:09.604 [0x7f1963f46840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Top Gun (1986).mkv' for reading
Jan 08, 2019 04:08:09.604 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:09.623 [0x7f1963f46840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:09.625 [0x7f1963f46840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:09.626 [0x7f1963f46840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:09.626 [0x7f1963f46840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:09.626 [0x7f1963f46840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 829 bytes read:65536 seeks:2 nb_streams:3
Jan 08, 2019 04:08:09.626 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:09.626 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:09.628 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:09.628 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:09.628 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:09.628 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:09.628 [0x7f1963f46840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:09.629 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:09.629 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:09.630 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:09.630 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:09.631 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:09.632 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:09.633 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:09.633 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:09.634 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:09.634 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:09.635 [0x7f1963f46840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:09.636 [0x7f1963f46840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 24724 bytes read:98304 seeks:2 frames:26
Jan 08, 2019 04:08:09.636 [0x7f1963f46840] DEBUG - [MI]  * 0fd7fd9bb456e7d0407e64536ee1c643502b5c70 [mkv h264/ac3] Duration: 6570022ms, 718x322 (1.981070) 23.976fps, bitrate=2200kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Top Gun (1986).mkv")
Jan 08, 2019 04:08:09.637 [0x7f1963f46840] DEBUG - [MI] Importing 30 media chapters.
Jan 08, 2019 04:08:09.637 [0x7f1963f46840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:09.642 [0x7f1963f46840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Top Gun (1986).mkv"
Jan 08, 2019 04:08:09.643 [0x7f1963f46840] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Jan 08, 2019 04:08:09.645 [0x7f1963f46840] DEBUG - Updating media item 15002, size=1807055206, metadata_item_id=8930
Jan 08, 2019 04:08:09.646 [0x7f1963f46840] DEBUG - Updating part with ID=22030 [/mnt/nfs/media/Videos/Movies/Top Gun (1986).mkv]
Jan 08, 2019 04:08:09.647 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8930&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:09.650 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8930&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:09.650 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8930&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:09.652 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8930&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:09.652 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8930&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:09.654 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8930&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:09.664 [0x7f1963f46840] DEBUG - Analyzing media parts for item 8620 (Back to the Future Part II): 9145
Jan 08, 2019 04:08:09.664 [0x7f1963f46840] DEBUG - [ID 16162] Media part analysis: /mnt/nfs/media/Videos/Movies/Back to the Future Part II (1989).mkv
Jan 08, 2019 04:08:09.664 [0x7f1963f46840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Back to the Future Part II (1989).mkv"
Jan 08, 2019 04:08:09.664 [0x7f1963f46840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Back to the Future Part II (1989).mkv' for reading
Jan 08, 2019 04:08:09.664 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:10.142 [0x7f1963f46840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:10.155 [0x7f1963f46840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:10.156 [0x7f1963f46840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:10.156 [0x7f1963f46840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 734 bytes read:63499 seeks:2 nb_streams:2
Jan 08, 2019 04:08:10.156 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:10.156 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:10.157 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:10.157 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:10.158 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:10.158 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:10.158 [0x7f1963f46840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:10.159 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:10.159 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.160 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:10.160 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:10.162 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:10.162 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.163 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.163 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:10.165 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:10.166 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.167 [0x7f1963f46840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:10.167 [0x7f1963f46840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 14579 bytes read:96267 seeks:2 frames:17
Jan 08, 2019 04:08:10.167 [0x7f1963f46840] DEBUG - [MI]  * c75aae857cb4534b36545ebf1472a288904b0310 [mkv h264/ac3] Duration: 6487457ms, 720x460 (1.854334) 29.970fps, bitrate=2562kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Back to the Future Part II (1989).mkv")
Jan 08, 2019 04:08:10.169 [0x7f1963f46840] DEBUG - [MI] Importing 20 media chapters.
Jan 08, 2019 04:08:10.169 [0x7f1963f46840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:10.173 [0x7f1963f46840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Back to the Future Part II (1989).mkv"
Jan 08, 2019 04:08:10.174 [0x7f1963f46840] DEBUG - [FFMPEG] - Statistics: 96267 bytes read, 2 seeks
Jan 08, 2019 04:08:10.176 [0x7f1963f46840] DEBUG - Updating media item 9145, size=2077727181, metadata_item_id=8620
Jan 08, 2019 04:08:10.176 [0x7f1963f46840] DEBUG - Updating part with ID=16162 [/mnt/nfs/media/Videos/Movies/Back to the Future Part II (1989).mkv]
Jan 08, 2019 04:08:10.178 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8620&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:10.180 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8620&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:10.180 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8620&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:10.181 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8620&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:10.182 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8620&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:10.183 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8620&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:10.192 [0x7f1963f46840] DEBUG - Analyzing media parts for item 8761 (The Bourne Identity): 12022
Jan 08, 2019 04:08:10.192 [0x7f1963f46840] DEBUG - [ID 19039] Media part analysis: /mnt/nfs/media/Videos/Movies/The Bourne Identity (2002).mkv
Jan 08, 2019 04:08:10.193 [0x7f1963f46840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/The Bourne Identity (2002).mkv"
Jan 08, 2019 04:08:10.194 [0x7f1963f46840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/The Bourne Identity (2002).mkv' for reading
Jan 08, 2019 04:08:10.194 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:10.645 [0x7f1963f46840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:10.648 [0x7f1963f46840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:10.648 [0x7f1963f46840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:10.648 [0x7f1963f46840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 734 bytes read:65536 seeks:2 nb_streams:2
Jan 08, 2019 04:08:10.648 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:10.648 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:10.649 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:10.649 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:10.650 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:10.650 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:10.650 [0x7f1963f46840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:10.651 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:10.652 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.652 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:10.653 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:10.654 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:10.654 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.655 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.655 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:10.656 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:10.657 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.658 [0x7f1963f46840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:10.658 [0x7f1963f46840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 14536 bytes read:98304 seeks:2 frames:17
Jan 08, 2019 04:08:10.658 [0x7f1963f46840] DEBUG - [MI]  * 9dd1c11f16534380a52e18239d2531002ee6f0bf [mkv h264/ac3] Duration: 7097537ms, 720x362 (2.356336) 29.970fps, bitrate=1675kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/The Bourne Identity (2002).mkv")
Jan 08, 2019 04:08:10.659 [0x7f1963f46840] DEBUG - [MI] Importing 20 media chapters.
Jan 08, 2019 04:08:10.660 [0x7f1963f46840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:10.663 [0x7f1963f46840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:10.663 [0x7f1963f46840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:10.666 [0x7f1963f46840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:10.667 [0x7f1963f46840] DEBUG - Updating metadata item (save) (The Bourne Identity) with ID 8761
Jan 08, 2019 04:08:10.676 [0x7f1963f46840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:10.676 [0x7f1963f46840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:10.679 [0x7f1963f46840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:10.679 [0x7f1963f46840] DEBUG - Updating metadata item (save) (The Bourne Identity) with ID 8761
Jan 08, 2019 04:08:10.694 [0x7f1963f46840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/The Bourne Identity (2002).mkv"
Jan 08, 2019 04:08:10.696 [0x7f1963f46840] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Jan 08, 2019 04:08:10.699 [0x7f1963f46840] DEBUG - Updating media item 12022, size=1486429440, metadata_item_id=8761
Jan 08, 2019 04:08:10.699 [0x7f1963f46840] DEBUG - Updating part with ID=19039 [/mnt/nfs/media/Videos/Movies/The Bourne Identity (2002).mkv]
Jan 08, 2019 04:08:10.701 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8761&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:10.703 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8761&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:10.703 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8761&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:10.705 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8761&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:10.705 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8761&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:10.706 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8761&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:10.707 [0x7f1963f46840] DEBUG - Analyzing media parts for item 7944 (M*A*S*H the Pilot): 21857
Jan 08, 2019 04:08:10.708 [0x7f1963f46840] DEBUG - [ID 29161] Media part analysis: /mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x01 - MASH the Pilot.mkv
Jan 08, 2019 04:08:10.708 [0x7f1963f46840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x01 - MASH the Pilot.mkv"
Jan 08, 2019 04:08:10.708 [0x7f1963f46840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x01 - MASH the Pilot.mkv' for reading
Jan 08, 2019 04:08:10.708 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:10.729 [0x7f1963f46840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:10.744 [0x7f1963f46840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:10.745 [0x7f1963f46840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:10.745 [0x7f1963f46840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:10.745 [0x7f1963f46840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:10.745 [0x7f1963f46840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7032 bytes read:45736 seeks:2 nb_streams:4
Jan 08, 2019 04:08:10.745 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:10.745 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x2c2c2c
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:10.746 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x2c2c2c
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:10.747 [0x7f1963f46840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:10.748 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:10.748 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:10.748 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:10.748 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:10.749 [0x7f1963f46840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:10.750 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:10.751 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.751 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:10.752 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.753 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:10.755 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:10.756 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.757 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:10.758 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:10.759 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:10.760 [0x7f1963f46840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:10.761 [0x7f1963f46840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 8273 bytes read:78504 seeks:2 frames:34
Jan 08, 2019 04:08:10.761 [0x7f1963f46840] DEBUG - [MI]  * 266185c4907bd5f0526a0d7a6b77e89e4482a57a [mkv h264/aac] Duration: 1534909ms, 640x480 (1.333333) 23.976fps, bitrate=957kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x01 - MASH the Pilot.mkv")
Jan 08, 2019 04:08:10.762 [0x7f1963f46840] DEBUG - [MI] Importing 11 media chapters.
Jan 08, 2019 04:08:10.762 [0x7f1963f46840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:10.765 [0x7f1963f46840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x01 - MASH the Pilot.mkv"
Jan 08, 2019 04:08:10.766 [0x7f1963f46840] DEBUG - [FFMPEG] - Statistics: 78504 bytes read, 2 seeks
Jan 08, 2019 04:08:10.767 [0x7f1963f46840] DEBUG - Committing media stream changes for part 29161
Jan 08, 2019 04:08:10.769 [0x7f1963f46840] DEBUG - Updating media item 21857, size=183730719, metadata_item_id=7944
Jan 08, 2019 04:08:10.770 [0x7f1963f46840] DEBUG - Updating part with ID=29161 [/mnt/nfs/media/Videos/TV Shows/MASH/Season 1/1x01 - MASH the Pilot.mkv]
Jan 08, 2019 04:08:10.771 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=7944&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:10.774 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=7944&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:10.774 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=7944&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:10.776 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=7944&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:10.777 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=7944&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:10.777 [0x7f1963f46840] DEBUG - Analyzing media parts for item 9764 (Springtime): 21840
Jan 08, 2019 04:08:10.778 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=7944&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:10.778 [0x7f1963f46840] DEBUG - [ID 29144] Media part analysis: /mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x06 - Springtime.mkv
Jan 08, 2019 04:08:10.778 [0x7f1963f46840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x06 - Springtime.mkv"
Jan 08, 2019 04:08:10.779 [0x7f1963f46840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x06 - Springtime.mkv' for reading
Jan 08, 2019 04:08:10.779 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:11.272 [0x7f1963f46840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:11.297 [0x7f1963f46840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7037 bytes read:45993 seeks:2 nb_streams:4
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:11.298 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] - palette:
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0xcbcbcb
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x0d0d0d
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] -  0x000000
Jan 08, 2019 04:08:11.299 [0x7f1963f46840] DEBUG - [FFMPEG] - 
Jan 08, 2019 04:08:11.300 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:11.300 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:11.300 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:11.301 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:11.301 [0x7f1963f46840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:11.302 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:11.303 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.303 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:11.304 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.305 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:11.307 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.308 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.309 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:11.310 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.311 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.312 [0x7f1963f46840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:11.313 [0x7f1963f46840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 8273 bytes read:78761 seeks:2 frames:34
Jan 08, 2019 04:08:11.313 [0x7f1963f46840] DEBUG - [MI]  * da9dc7c2ad8646cb230b4ac6932fcef6a4623cfb [mkv h264/aac] Duration: 1568000ms, 640x480 (1.333333) 23.976fps, bitrate=937kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x06 - Springtime.mkv")
Jan 08, 2019 04:08:11.314 [0x7f1963f46840] DEBUG - [MI] Importing 11 media chapters.
Jan 08, 2019 04:08:11.314 [0x7f1963f46840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:11.317 [0x7f1963f46840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x06 - Springtime.mkv"
Jan 08, 2019 04:08:11.318 [0x7f1963f46840] DEBUG - [FFMPEG] - Statistics: 78761 bytes read, 2 seeks
Jan 08, 2019 04:08:11.319 [0x7f1963f46840] DEBUG - Committing media stream changes for part 29144
Jan 08, 2019 04:08:11.321 [0x7f1963f46840] DEBUG - Updating media item 21840, size=183765834, metadata_item_id=9764
Jan 08, 2019 04:08:11.322 [0x7f1963f46840] DEBUG - Updating part with ID=29144 [/mnt/nfs/media/Videos/TV Shows/MASH/Season 3/3x06 - Springtime.mkv]
Jan 08, 2019 04:08:11.323 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9764&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:11.326 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9764&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:11.326 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9764&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:11.328 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9764&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:11.328 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9764&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:11.328 [0x7f1963f46840] DEBUG - Analyzing media parts for item 9185 (Herb's Dad): 18663
Jan 08, 2019 04:08:11.330 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9764&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:11.330 [0x7f1963f46840] DEBUG - [ID 25795] Media part analysis: /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E15 - Herb's Dad.mp4
Jan 08, 2019 04:08:11.330 [0x7f1963f46840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E15 - Herb's Dad.mp4"
Jan 08, 2019 04:08:11.330 [0x7f1963f46840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E15 - Herb's Dad.mp4' for reading
Jan 08, 2019 04:08:11.330 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:11.359 [0x7f1963f46840] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Jan 08, 2019 04:08:11.359 [0x7f1963f46840] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Jan 08, 2019 04:08:11.368 [0x7f1963f46840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:11.410 [0x7f1963f46840] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 6006, duration: 134288154
Jan 08, 2019 04:08:11.416 [0x7f1963f46840] DEBUG - [FFMPEG] - Offset DTS by 6006 to make first pts zero.
Jan 08, 2019 04:08:11.425 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Jan 08, 2019 04:08:11.425 [0x7f1963f46840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:11.430 [0x7f1963f46840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:11.434 [0x7f1963f46840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:11.434 [0x7f1963f46840] DEBUG - [FFMPEG] - Processing st: 3, edit list 0 - media time: 6006, duration: 134288154
Jan 08, 2019 04:08:11.434 [0x7f1963f46840] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Jan 08, 2019 04:08:11.474 [0x7f1963f46840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 415228194 bytes read:1270895 seeks:8 nb_streams:4
Jan 08, 2019 04:08:11.474 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:11.474 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:11.475 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:11.475 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:11.475 [0x7f1963f46840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:11.476 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:11.478 [0x7f1963f46840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:11.479 [0x7f1963f46840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 13908 bytes read:1303663 seeks:9 frames:13
Jan 08, 2019 04:08:11.479 [0x7f1963f46840] DEBUG - [MI] Opening "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E15 - Herb's Dad.mp4" for MP4 atom parsing
Jan 08, 2019 04:08:11.480 [0x7f1963f46840] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Jan 08, 2019 04:08:11.480 [0x7f1963f46840] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Jan 08, 2019 04:08:11.480 [0x7f1963f46840] DEBUG - Mp4AtomParser: Skipping mdat atom of size 414125961 at offset 160
Jan 08, 2019 04:08:11.480 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found moov atom of size 1102049 at offset 414126121
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found trak atom of size 666056 at offset 414126261
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found trak atom of size 360154 at offset 414792317
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found trak atom of size 75016 at offset 415152471
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found trak atom of size 572 at offset 415227487
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found mdia atom of size 665900 at offset 414126361
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found minf atom of size 665827 at offset 414126434
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found stbl atom of size 665763 at offset 414126498
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found co64 atom of size 89456 at offset 414305655
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - [MI] File has co64 atom with size 89456 at offset 414305655
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 414792261
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 414792269
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - [MI] Determined stream id 4 has chapters, not subtitles
Jan 08, 2019 04:08:11.481 [0x7f1963f46840] DEBUG - [MI]  * 76e456edfaff1c5786c8481e035c723d75aa01a5 [mp4 h264/aac] Duration: 1492091ms, 706x480 (1.307407) 29.970fps, bitrate=2226kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E15 - Herb's Dad.mp4")
Jan 08, 2019 04:08:11.482 [0x7f1963f46840] DEBUG - [MI] Importing 5 media chapters.
Jan 08, 2019 04:08:11.482 [0x7f1963f46840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:11.484 [0x7f1963f46840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E15 - Herb's Dad.mp4"
Jan 08, 2019 04:08:11.485 [0x7f1963f46840] DEBUG - [FFMPEG] - Statistics: 1303663 bytes read, 9 seeks
Jan 08, 2019 04:08:11.487 [0x7f1963f46840] DEBUG - Updating media item 18663, size=415228302, metadata_item_id=9185
Jan 08, 2019 04:08:11.488 [0x7f1963f46840] DEBUG - Updating part with ID=25795 [/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02/WKRP in Cincinnati - S02E15 - Herb's Dad.mp4]
Jan 08, 2019 04:08:11.490 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9185&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:11.493 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9185&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:11.493 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9185&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:11.494 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9185&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:11.495 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9185&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:11.496 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9185&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:11.503 [0x7f1963f46840] DEBUG - Analyzing media parts for item 8691 (Lethal Weapon 4): 10087
Jan 08, 2019 04:08:11.503 [0x7f1963f46840] DEBUG - [ID 17104] Media part analysis: /mnt/nfs/media/Videos/Movies/Lethal Weapon 4 (1998).mkv
Jan 08, 2019 04:08:11.503 [0x7f1963f46840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Lethal Weapon 4 (1998).mkv"
Jan 08, 2019 04:08:11.503 [0x7f1963f46840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Lethal Weapon 4 (1998).mkv' for reading
Jan 08, 2019 04:08:11.503 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:11.526 [0x7f1963f46840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:11.548 [0x7f1963f46840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:11.548 [0x7f1963f46840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:11.548 [0x7f1963f46840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 734 bytes read:65536 seeks:2 nb_streams:2
Jan 08, 2019 04:08:11.549 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:11.549 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:11.549 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:11.549 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:11.550 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:11.550 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:11.550 [0x7f1963f46840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:11.551 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:11.551 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.552 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:11.552 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:11.553 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.553 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.554 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:11.555 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:11.556 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.556 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:11.557 [0x7f1963f46840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:12.009 [0x7f1963f46840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 17457 bytes read:98304 seeks:2 frames:20
Jan 08, 2019 04:08:12.009 [0x7f1963f46840] DEBUG - [MI]  * a09130a60e7c47deb9db106fe5a94080e240b71c [mkv h264/ac3] Duration: 7645638ms, 720x358 (2.382664) 23.976fps, bitrate=2559kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Lethal Weapon 4 (1998).mkv")
Jan 08, 2019 04:08:12.010 [0x7f1963f46840] DEBUG - [MI] Importing 45 media chapters.
Jan 08, 2019 04:08:12.011 [0x7f1963f46840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:12.015 [0x7f1963f46840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:12.016 [0x7f1963f46840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:12.018 [0x7f1963f46840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:12.019 [0x7f1963f46840] DEBUG - Updating metadata item (save) (Lethal Weapon 4) with ID 8691
Jan 08, 2019 04:08:12.032 [0x7f1963f46840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:12.033 [0x7f1963f46840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:12.035 [0x7f1963f46840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Jan 08, 2019 04:08:12.036 [0x7f1963f46840] DEBUG - Updating metadata item (save) (Lethal Weapon 4) with ID 8691
Jan 08, 2019 04:08:12.038 [0x7f1963f46840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Lethal Weapon 4 (1998).mkv"
Jan 08, 2019 04:08:12.040 [0x7f1963f46840] DEBUG - [FFMPEG] - Statistics: 98304 bytes read, 2 seeks
Jan 08, 2019 04:08:12.043 [0x7f1963f46840] DEBUG - Updating media item 10087, size=2446233666, metadata_item_id=8691
Jan 08, 2019 04:08:12.044 [0x7f1963f46840] DEBUG - Updating part with ID=17104 [/mnt/nfs/media/Videos/Movies/Lethal Weapon 4 (1998).mkv]
Jan 08, 2019 04:08:12.046 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8691&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:12.048 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8691&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:12.049 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8691&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:12.050 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8691&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:12.051 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8691&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:12.051 [0x7f1963f46840] DEBUG - Analyzing media parts for item 9153 (Hotel Oceanview): 18632
Jan 08, 2019 04:08:12.052 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8691&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:12.053 [0x7f1963f46840] DEBUG - [ID 25764] Media part analysis: /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E05 - Hotel Oceanview.mp4
Jan 08, 2019 04:08:12.053 [0x7f1963f46840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E05 - Hotel Oceanview.mp4"
Jan 08, 2019 04:08:12.053 [0x7f1963f46840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E05 - Hotel Oceanview.mp4' for reading
Jan 08, 2019 04:08:12.053 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:12.087 [0x7f1963f46840] DEBUG - [FFMPEG] - Format mov,mp4,m4a,3gp,3g2,mj2 probed with size=2048 and score=100
Jan 08, 2019 04:08:12.087 [0x7f1963f46840] DEBUG - [FFMPEG] - ISO: File Type Major Brand: mp42
Jan 08, 2019 04:08:12.120 [0x7f1963f46840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:12.608 [0x7f1963f46840] DEBUG - [FFMPEG] - Processing st: 0, edit list 0 - media time: 6006, duration: 131122992
Jan 08, 2019 04:08:12.612 [0x7f1963f46840] DEBUG - [FFMPEG] - Offset DTS by 6006 to make first pts zero.
Jan 08, 2019 04:08:12.621 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting codecpar->delay to 2 for stream st: 0
Jan 08, 2019 04:08:12.622 [0x7f1963f46840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:12.626 [0x7f1963f46840] DEBUG - [FFMPEG] - Unknown dref type 0x206c7275 size 12
Jan 08, 2019 04:08:12.626 [0x7f1963f46840] DEBUG - [FFMPEG] - Processing st: 2, edit list 0 - media time: 6006, duration: 131047917
Jan 08, 2019 04:08:12.626 [0x7f1963f46840] DEBUG - [FFMPEG] - drop a frame at curr_cts: 0 @ 0
Jan 08, 2019 04:08:12.714 [0x7f1963f46840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 208778644 bytes read:1078621 seeks:8 nb_streams:3
Jan 08, 2019 04:08:12.714 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:12.714 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:12.715 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:12.715 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:12.715 [0x7f1963f46840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:12.715 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:12.716 [0x7f1963f46840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:12.720 [0x7f1963f46840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 3603 bytes read:1111389 seeks:9 frames:8
Jan 08, 2019 04:08:12.720 [0x7f1963f46840] DEBUG - [MI] Opening "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E05 - Hotel Oceanview.mp4" for MP4 atom parsing
Jan 08, 2019 04:08:12.720 [0x7f1963f46840] DEBUG - Mp4AtomParser: Skipping ftyp atom of size 28 at offset 0
Jan 08, 2019 04:08:12.720 [0x7f1963f46840] DEBUG - Mp4AtomParser: Skipping free atom of size 132 at offset 28
Jan 08, 2019 04:08:12.720 [0x7f1963f46840] DEBUG - Mp4AtomParser: Skipping mdat atom of size 207881279 at offset 160
Jan 08, 2019 04:08:12.720 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found moov atom of size 897181 at offset 207881439
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found trak atom of size 583708 at offset 207881579
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found trak atom of size 312670 at offset 208465287
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found trak atom of size 552 at offset 208777957
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found mdia atom of size 583552 at offset 207881679
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found minf atom of size 583479 at offset 207881752
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found stbl atom of size 583415 at offset 207881816
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found tref atom of size 20 at offset 208465231
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found chap atom of size 12 at offset 208465239
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - [MI] Determined stream id 3 has chapters, not subtitles
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found mdia atom of size 312548 at offset 208465387
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found minf atom of size 312475 at offset 208465460
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found stbl atom of size 312415 at offset 208465520
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found mdia atom of size 416 at offset 208778057
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found minf atom of size 343 at offset 208778130
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - Mp4AtomParser: Found stbl atom of size 223 at offset 208778250
Jan 08, 2019 04:08:12.721 [0x7f1963f46840] DEBUG - [MI]  * 4c269f0a1495f78c6f210a42acd5695cc53d25bf [mp4 h264/aac] Duration: 1456939ms, 706x478 (1.312878) 29.970fps, bitrate=1146kbps, audio channels=2, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E05 - Hotel Oceanview.mp4")
Jan 08, 2019 04:08:12.722 [0x7f1963f46840] DEBUG - [MI] Importing 5 media chapters.
Jan 08, 2019 04:08:12.723 [0x7f1963f46840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:12.724 [0x7f1963f46840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E05 - Hotel Oceanview.mp4"
Jan 08, 2019 04:08:12.725 [0x7f1963f46840] DEBUG - [FFMPEG] - Statistics: 1111389 bytes read, 9 seeks
Jan 08, 2019 04:08:12.727 [0x7f1963f46840] DEBUG - Updating media item 18632, size=208778752, metadata_item_id=9153
Jan 08, 2019 04:08:12.728 [0x7f1963f46840] DEBUG - Updating part with ID=25764 [/mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03/WKRP in Cincinnati - S03E05 - Hotel Oceanview.mp4]
Jan 08, 2019 04:08:12.729 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9153&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:12.731 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9153&metadataType=4&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:12.732 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9153&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:12.733 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9153&metadataType=4&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:12.734 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9153&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:12.735 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=3&metadataItemID=9153&metadataType=4&state=-1&mediaState=idle
Jan 08, 2019 04:08:12.744 [0x7f1963f46840] DEBUG - Analyzing media parts for item 8649 (Shrek): 9835
Jan 08, 2019 04:08:12.744 [0x7f1963f46840] DEBUG - [ID 16852] Media part analysis: /mnt/nfs/media/Videos/Movies/Shrek (2006).mkv
Jan 08, 2019 04:08:12.745 [0x7f1963f46840] DEBUG - [MI] Opening input file: "/mnt/nfs/media/Videos/Movies/Shrek (2006).mkv"
Jan 08, 2019 04:08:12.745 [0x7f1963f46840] DEBUG - [FFMPEG] - Opening '/mnt/nfs/media/Videos/Movies/Shrek (2006).mkv' for reading
Jan 08, 2019 04:08:12.745 [0x7f1963f46840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Jan 08, 2019 04:08:12.768 [0x7f1963f46840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Jan 08, 2019 04:08:12.786 [0x7f1963f46840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:12.786 [0x7f1963f46840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Jan 08, 2019 04:08:12.787 [0x7f1963f46840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 741 bytes read:59090 seeks:2 nb_streams:2
Jan 08, 2019 04:08:12.787 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:12.787 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:12.788 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Jan 08, 2019 04:08:12.788 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Jan 08, 2019 04:08:12.789 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Jan 08, 2019 04:08:12.789 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:12.789 [0x7f1963f46840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Jan 08, 2019 04:08:12.790 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:12.791 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:12.792 [0x7f1963f46840] DEBUG - [FFMPEG] - no picture 
Jan 08, 2019 04:08:12.792 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:12.794 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Jan 08, 2019 04:08:12.795 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:12.796 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:12.796 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:12.798 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Jan 08, 2019 04:08:12.799 [0x7f1963f46840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Jan 08, 2019 04:08:12.800 [0x7f1963f46840] DEBUG - [FFMPEG] - All info found
Jan 08, 2019 04:08:12.800 [0x7f1963f46840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 14595 bytes read:91858 seeks:2 frames:17
Jan 08, 2019 04:08:12.800 [0x7f1963f46840] DEBUG - [MI]  * 08fc032b1e57ac08ebbfa3ccf0165f657de81417 [mkv h264/ac3] Duration: 5404193ms, 720x480 (1.333333) 29.970fps, bitrate=1948kbps, audio channels=6, optimizedForStreaming=0 ("/mnt/nfs/media/Videos/Movies/Shrek (2006).mkv")
Jan 08, 2019 04:08:12.801 [0x7f1963f46840] DEBUG - [MI] Importing 20 media chapters.
Jan 08, 2019 04:08:12.801 [0x7f1963f46840] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 08, 2019 04:08:12.805 [0x7f1963f46840] DEBUG - [MI] Closing input file: "/mnt/nfs/media/Videos/Movies/Shrek (2006).mkv"
Jan 08, 2019 04:08:12.806 [0x7f1963f46840] DEBUG - [FFMPEG] - Statistics: 91858 bytes read, 2 seeks
Jan 08, 2019 04:08:12.808 [0x7f1963f46840] DEBUG - Updating media item 9835, size=1316445902, metadata_item_id=8649
Jan 08, 2019 04:08:12.809 [0x7f1963f46840] DEBUG - Updating part with ID=16852 [/mnt/nfs/media/Videos/Movies/Shrek (2006).mkv]
Jan 08, 2019 04:08:12.810 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8649&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:12.813 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8649&metadataType=1&state=-1&mediaState=analyzing
Jan 08, 2019 04:08:12.813 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8649&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:12.815 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8649&metadataType=1&state=-1&mediaState=thumbnailing
Jan 08, 2019 04:08:12.815 [0x7f194d7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8649&metadataType=1&state=-1&mediaState=idle
Jan 08, 2019 04:08:12.817 [0x7f194d7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=8649&metadataType=1&state=-1&mediaState=idle
