Nov 27, 2018 14:28:13.547 [0x7f1eddfff700] INFO - Plex Media Scanner v1.14.0.5470-9d51fdfaa - ubuntu docker x86_64 - build: linux-ubuntu-x86_64 - GMT -05:00
Nov 27, 2018 14:28:13.548 [0x7f1eddfff700] INFO - Linux version: 4.19.1 (#1 SMP Sun Nov 11 07:20:56 EST 2018), language: en-US
Nov 27, 2018 14:28:13.548 [0x7f1eddfff700] INFO - Processor       Intel(R) Atom(TM) x5-Z8350  CPU @ 1.44GHz
Nov 27, 2018 14:28:13.548 [0x7f1eddfff700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --log-file-suffix  Analysis --item 440
Nov 27, 2018 14:28:13.624 [0x7f1ee99fd840] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Nov 27, 2018 14:28:14.197 [0x7f1ee99fd840] DEBUG - Analyzing media parts for item 440 (El retorno del rey): 625
Nov 27, 2018 14:28:14.198 [0x7f1ee99fd840] DEBUG - [ID 626] Media part analysis: /data/movies/peliculas/El retorno del rey.mkv
Nov 27, 2018 14:28:14.198 [0x7f1ee99fd840] DEBUG - [MI] Opening input file: "/data/movies/peliculas/El retorno del rey.mkv"
Nov 27, 2018 14:28:14.199 [0x7f1ee99fd840] DEBUG - [FFMPEG] - Opening '/data/movies/peliculas/El retorno del rey.mkv' for reading
Nov 27, 2018 14:28:14.200 [0x7f1ee99fd840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 27, 2018 14:28:14.228 [0x7f1ee99fd840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Nov 27, 2018 14:28:14.278 [0x7f1ee99fd840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Nov 27, 2018 14:28:14.278 [0x7f1ee99fd840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Nov 27, 2018 14:28:14.278 [0x7f1ee99fd840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Nov 27, 2018 14:28:14.278 [0x7f1ee99fd840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Nov 27, 2018 14:28:14.278 [0x7f1ee99fd840] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Nov 27, 2018 14:28:14.279 [0x7f1ee99fd840] DEBUG - [FFMPEG] - st:5 removing common factor 1000000 from timebase
Nov 27, 2018 14:28:14.279 [0x7f1ee99fd840] DEBUG - [FFMPEG] - st:6 removing common factor 1000000 from timebase
Nov 27, 2018 14:28:14.280 [0x7f1ee99fd840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 11422 bytes read:161110 seeks:2 nb_streams:8
Nov 27, 2018 14:28:14.281 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 27, 2018 14:28:14.281 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 27, 2018 14:28:14.308 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 27, 2018 14:28:14.308 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 27, 2018 14:28:14.310 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Nov 27, 2018 14:28:14.310 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Nov 27, 2018 14:28:14.310 [0x7f1ee99fd840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Nov 27, 2018 14:28:14.314 [0x7f1ee99fd840] DEBUG - [FFMPEG] - no picture 
Nov 27, 2018 14:28:14.323 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 27, 2018 14:28:14.327 [0x7f1ee99fd840] DEBUG - [FFMPEG] - no picture 
Nov 27, 2018 14:28:14.331 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 27, 2018 14:28:14.336 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 27, 2018 14:28:14.341 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 27, 2018 14:28:14.346 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 27, 2018 14:28:14.351 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 27, 2018 14:28:14.357 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 27, 2018 14:28:14.363 [0x7f1ee99fd840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 27, 2018 14:28:14.368 [0x7f1ee99fd840] DEBUG - [FFMPEG] - All info found
Nov 27, 2018 14:28:14.369 [0x7f1ee99fd840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 19089 bytes read:193878 seeks:2 frames:42
Nov 27, 2018 14:28:14.375 [0x7f1ee99fd840] DEBUG - [MI]  * 20e26fbcc8b8665208a10d0a95ac5abca17ea066 [mkv h264/aac] Duration: 15143595ms, 1280x528 (2.424242) 25.000fps, bitrate=1659kbps, audio channels=6, optimizedForStreaming=0 ("/data/movies/peliculas/El retorno del rey.mkv")
Nov 27, 2018 14:28:14.377 [0x7f1ee99fd840] DEBUG - [MI] Importing 78 media chapters.
Nov 27, 2018 14:28:14.396 [0x7f1ee99fd840] DEBUG - Doing expensive tags write for 'El retorno del rey' because something changed.
Nov 27, 2018 14:28:14.528 [0x7f1ee99fd840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 27, 2018 14:28:14.529 [0x7f1ee99fd840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 27, 2018 14:28:14.537 [0x7f1ee99fd840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 27, 2018 14:28:14.539 [0x7f1ee99fd840] DEBUG - Updating metadata item (save) (El retorno del rey) with ID 440
Nov 27, 2018 14:28:14.553 [0x7f1ee99fd840] DEBUG - [MI] Closing input file: "/data/movies/peliculas/El retorno del rey.mkv"
Nov 27, 2018 14:28:14.558 [0x7f1ee99fd840] DEBUG - [FFMPEG] - Statistics: 193878 bytes read, 2 seeks
Nov 27, 2018 14:28:14.562 [0x7f1ee99fd840] DEBUG - Committing media stream changes for part 626
Nov 27, 2018 14:28:14.570 [0x7f1ee99fd840] DEBUG - Updating media item 625, size=3141444371, metadata_item_id=440
Nov 27, 2018 14:28:14.571 [0x7f1ee99fd840] DEBUG - Updating part with ID=626 [/data/movies/peliculas/El retorno del rey.mkv]
Nov 27, 2018 14:28:14.576 [0x7f1ee99fd840] DEBUG - Loading Movie 'El retorno del rey' XML from /config/Library/Application Support/Plex Media Server/Metadata/Movies/8/c8350f416410ef8a9c316c3ebdf6f5d10d3440a.bundle/Contents/_combined
Nov 27, 2018 14:28:14.577 [0x7f1ed9bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=440&metadataType=1&state=-1&mediaState=analyzing
Nov 27, 2018 14:28:14.578 [0x7f1ee99fd840] DEBUG - Item 440 (El retorno del rey) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 27, 2018 14:28:14.582 [0x7f1ed8bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F1361%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_85366a6a15425a5b5d62584031d4ff6d2360e5bb
Nov 27, 2018 14:28:14.583 [0x7f1ed9bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=440&metadataType=1&state=-1&mediaState=analyzing
Nov 27, 2018 14:28:14.585 [0x7f1ed9bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=440&metadataType=1&state=-1&mediaState=thumbnailing
Nov 27, 2018 14:28:14.593 [0x7f1ed9bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=440&metadataType=1&state=-1&mediaState=thumbnailing
Nov 27, 2018 14:28:14.594 [0x7f1ed9bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=440&metadataType=1&state=-1&mediaState=idle
Nov 27, 2018 14:28:14.598 [0x7f1ed9bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=440&metadataType=1&state=-1&mediaState=idle
Nov 27, 2018 14:28:14.735 [0x7f1ed8bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F1361%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_85366a6a15425a5b5d62584031d4ff6d2360e5bb
Nov 27, 2018 14:28:14.736 [0x7f1ee99fd840] DEBUG - Brought media local (metadata://posters/com.plexapp.agents.themoviedb_85366a6a15425a5b5d62584031d4ff6d2360e5bb) in 0.0s.
Nov 27, 2018 14:28:14.737 [0x7f1ee99fd840] DEBUG - Item 440 (El retorno del rey) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 27, 2018 14:28:14.743 [0x7f1ed7bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F1361%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_bacae20b2cd80efb3908181441c958fe34e6a6a1
Nov 27, 2018 14:28:14.787 [0x7f1ed7bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F1361%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_bacae20b2cd80efb3908181441c958fe34e6a6a1
Nov 27, 2018 14:28:14.788 [0x7f1ee99fd840] DEBUG - Brought media local (metadata://art/com.plexapp.agents.themoviedb_bacae20b2cd80efb3908181441c958fe34e6a6a1) in 0.0s.
Nov 27, 2018 14:28:14.812 [0x7f1ee99fd840] DEBUG - Loading information from media bundle for part ID 626 from "/config/Library/Application Support/Plex Media Server/Media/localhost/2/0e26fbcc8b8665208a10d0a95ac5abca17ea066.bundle/Contents"
Nov 27, 2018 14:28:14.813 [0x7f1ee99fd840] DEBUG - Loaded metadata for El retorno del rey (ID 440) in 70.000000 ms
Nov 27, 2018 14:28:14.813 [0x7f1ee99fd840] DEBUG - There was a change for metadata item 440 (El retorno del rey), saving.
Nov 27, 2018 14:28:14.814 [0x7f1ee99fd840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 27, 2018 14:28:14.814 [0x7f1ee99fd840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 27, 2018 14:28:14.822 [0x7f1ee99fd840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 27, 2018 14:28:14.823 [0x7f1ee99fd840] DEBUG - Updating metadata item (save) (El retorno del rey) with ID 440
Nov 27, 2018 14:28:14.852 [0x7f1ee99fd840] DEBUG - Done with metadata update for 440
Nov 27, 2018 14:28:14.853 [0x7f1ed9bff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=440&metadataType=1&state=5&metadataState=idle
Nov 27, 2018 14:28:14.854 [0x7f1ee99fd840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 27, 2018 14:28:14.856 [0x7f1ee99fd840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 27, 2018 14:28:14.860 [0x7f1ed9bff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=440&metadataType=1&state=5&metadataState=idle
Nov 27, 2018 14:28:14.864 [0x7f1ee99fd840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 27, 2018 14:28:14.865 [0x7f1ee99fd840] DEBUG - Updating metadata item (save) (El retorno del rey) with ID 440
