Nov 28, 2018 02:05:02.593 [0x7ff8403ff700] INFO - Plex Media Scanner v1.14.0.5470-9d51fdfaa - ubuntu docker x86_64 - build: linux-ubuntu-x86_64 - GMT -05:00
Nov 28, 2018 02:05:02.594 [0x7ff8403ff700] INFO - Linux version: 4.19.1 (#1 SMP Sun Nov 11 07:20:56 EST 2018), language: en-US
Nov 28, 2018 02:05:02.594 [0x7ff8403ff700] INFO - Processor       Intel(R) Atom(TM) x5-Z8350  CPU @ 1.44GHz
Nov 28, 2018 02:05:02.594 [0x7ff8403ff700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --analyze --item 28,19,21,3,361 --log-file-suffix  Analysis
Nov 28, 2018 02:05:02.594 [0x7ff84bdab840] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Nov 28, 2018 02:05:03.148 [0x7ff84bdab840] DEBUG - Analyzing media parts for item 28 (Un día de furia): 41
Nov 28, 2018 02:05:03.149 [0x7ff84bdab840] DEBUG - [ID 42] Media part analysis: /data/movies/peliculas/Un dia de Furia BDR1080.mkv
Nov 28, 2018 02:05:03.150 [0x7ff84bdab840] DEBUG - [MI] Opening input file: "/data/movies/peliculas/Un dia de Furia BDR1080.mkv"
Nov 28, 2018 02:05:03.151 [0x7ff84bdab840] DEBUG - [FFMPEG] - Opening '/data/movies/peliculas/Un dia de Furia BDR1080.mkv' for reading
Nov 28, 2018 02:05:03.151 [0x7ff84bdab840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 28, 2018 02:05:03.197 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Nov 28, 2018 02:05:03.213 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.214 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.214 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.214 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.214 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.215 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:5 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.215 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:6 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.215 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:7 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.215 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:8 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.215 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:9 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.216 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:10 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.216 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:11 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.216 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:12 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.216 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:13 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.216 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:14 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.217 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:15 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.217 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:16 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.217 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:17 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.217 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:18 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:03.219 [0x7ff84bdab840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 5741 bytes read:40462 seeks:2 nb_streams:19
Nov 28, 2018 02:05:03.245 [0x7ff84bdab840] DEBUG - [FFMPEG] - Header: DB7E3BF2
Nov 28, 2018 02:05:03.245 [0x7ff84bdab840] DEBUG - [FFMPEG] - Advanced Profile level 3:
frmrtq_postproc=5, bitrtq_postproc=31
LoopFilter=0, ChromaFormat=1, Pulldown=1, Interlace: 0
TFCTRflag=0, FINTERPflag=0
Nov 28, 2018 02:05:03.246 [0x7ff84bdab840] DEBUG - [FFMPEG] - Display extended info:
Nov 28, 2018 02:05:03.246 [0x7ff84bdab840] DEBUG - [FFMPEG] - Display dimensions: 1920x1080
Nov 28, 2018 02:05:03.246 [0x7ff84bdab840] DEBUG - [FFMPEG] - Aspect: 1:1
Nov 28, 2018 02:05:03.246 [0x7ff84bdab840] DEBUG - [FFMPEG] - Entry point: 5ADFF840
Nov 28, 2018 02:05:03.246 [0x7ff84bdab840] DEBUG - [FFMPEG] - Entry point info:
BrokenLink=0, ClosedEntry=1, PanscanFlag=0
RefDist=1, Postproc=1, FastUVMC=0, ExtMV=1
DQuant=1, VSTransform=1, Overlap=0, Qmode=3
Nov 28, 2018 02:05:03.246 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Nov 28, 2018 02:05:03.248 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Nov 28, 2018 02:05:03.264 [0x7ff84bdab840] DEBUG - [FFMPEG] - Header: DB7E3BF2
Nov 28, 2018 02:05:03.264 [0x7ff84bdab840] DEBUG - [FFMPEG] - Advanced Profile level 3:
frmrtq_postproc=5, bitrtq_postproc=31
LoopFilter=0, ChromaFormat=1, Pulldown=1, Interlace: 0
TFCTRflag=0, FINTERPflag=0
Nov 28, 2018 02:05:03.264 [0x7ff84bdab840] DEBUG - [FFMPEG] - Display extended info:
Nov 28, 2018 02:05:03.264 [0x7ff84bdab840] DEBUG - [FFMPEG] - Display dimensions: 1920x1080
Nov 28, 2018 02:05:03.264 [0x7ff84bdab840] DEBUG - [FFMPEG] - Aspect: 1:1
Nov 28, 2018 02:05:03.264 [0x7ff84bdab840] DEBUG - [FFMPEG] - Entry point: 5ADFF840
Nov 28, 2018 02:05:03.264 [0x7ff84bdab840] DEBUG - [FFMPEG] - Entry point info:
BrokenLink=0, ClosedEntry=1, PanscanFlag=0
RefDist=1, Postproc=1, FastUVMC=0, ExtMV=1
DQuant=1, VSTransform=1, Overlap=0, Qmode=3
Nov 28, 2018 02:05:03.490 [0x7ff84bdab840] DEBUG - [FFMPEG] - Probe buffer size limit of 10000000 bytes reached
Nov 28, 2018 02:05:03.491 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 3 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.491 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 4 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.491 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 5 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.491 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 6 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.491 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 7 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.491 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 8 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.491 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 9 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.491 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 10 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.491 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 11 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.491 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 12 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.492 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 13 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.492 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 14 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.492 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 15 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.492 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 16 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.492 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 17 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.492 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 18 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:03.492 [0x7ff84bdab840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 10122120 bytes read:10156841 seeks:2 frames:5696
Nov 28, 2018 02:05:03.520 [0x7ff84bdab840] DEBUG - [MI]  * c5c58abfd585f7f56c54d3372d811eb765d63c9b [mkv vc1/ac3] Duration: 6756352ms, 1920x1080 (1.777778) 23.976fps, bitrate=21404kbps, audio channels=2, optimizedForStreaming=0 ("/data/movies/peliculas/Un dia de Furia BDR1080.mkv")
Nov 28, 2018 02:05:03.524 [0x7ff84bdab840] DEBUG - [MI] Importing 33 media chapters.
Nov 28, 2018 02:05:03.527 [0x7ff84bdab840] DEBUG - [MI] Chapters had generic titles, clearing.
Nov 28, 2018 02:05:03.542 [0x7ff84bdab840] DEBUG - Doing expensive tags write for 'Un día de furia' because something changed.
Nov 28, 2018 02:05:03.589 [0x7ff84bdab840] DEBUG - [MI] Closing input file: "/data/movies/peliculas/Un dia de Furia BDR1080.mkv"
Nov 28, 2018 02:05:03.618 [0x7ff84bdab840] DEBUG - [FFMPEG] - Statistics: 10156841 bytes read, 2 seeks
Nov 28, 2018 02:05:03.627 [0x7ff84bdab840] DEBUG - Committing media stream changes for part 42
Nov 28, 2018 02:05:03.672 [0x7ff84bdab840] DEBUG - Updating media item 41, size=18077056407, metadata_item_id=28
Nov 28, 2018 02:05:03.675 [0x7ff84bdab840] DEBUG - Updating part with ID=42 [/data/movies/peliculas/Un dia de Furia BDR1080.mkv]
Nov 28, 2018 02:05:03.682 [0x7ff84bdab840] DEBUG - Loading Movie 'Un día de furia' XML from /config/Library/Application Support/Plex Media Server/Metadata/Movies/0/5be0deac5d12e4dfb48b76cb7f13e9541be5c5d.bundle/Contents/_combined
Nov 28, 2018 02:05:03.683 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28&metadataType=1&state=-1&mediaState=analyzing
Nov 28, 2018 02:05:03.691 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28&metadataType=1&state=-1&mediaState=analyzing
Nov 28, 2018 02:05:03.693 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28&metadataType=1&state=-1&mediaState=thumbnailing
Nov 28, 2018 02:05:03.697 [0x7ff84bdab840] DEBUG - Item 28 (Un día de furia) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 28, 2018 02:05:03.698 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28&metadataType=1&state=-1&mediaState=thumbnailing
Nov 28, 2018 02:05:03.700 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28&metadataType=1&state=-1&mediaState=idle
Nov 28, 2018 02:05:03.704 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28&metadataType=1&state=-1&mediaState=idle
Nov 28, 2018 02:05:03.708 [0x7ff83afff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F37094%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_9787e8ca0d8b31be6ec1ce4777c290a59654b1b4
Nov 28, 2018 02:05:03.990 [0x7ff83afff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F37094%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_9787e8ca0d8b31be6ec1ce4777c290a59654b1b4
Nov 28, 2018 02:05:03.993 [0x7ff84bdab840] DEBUG - Brought media local (metadata://posters/com.plexapp.agents.themoviedb_9787e8ca0d8b31be6ec1ce4777c290a59654b1b4) in 0.0s.
Nov 28, 2018 02:05:03.993 [0x7ff84bdab840] DEBUG - Item 28 (Un día de furia) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 28, 2018 02:05:04.002 [0x7ff839fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F37094%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_1a8941004e685eb7af57548bd37709c2efb65479
Nov 28, 2018 02:05:04.172 [0x7ff839fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F37094%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_1a8941004e685eb7af57548bd37709c2efb65479
Nov 28, 2018 02:05:04.175 [0x7ff84bdab840] DEBUG - Brought media local (metadata://art/com.plexapp.agents.themoviedb_1a8941004e685eb7af57548bd37709c2efb65479) in 0.0s.
Nov 28, 2018 02:05:04.204 [0x7ff84bdab840] DEBUG - Loading information from media bundle for part ID 42 from "/config/Library/Application Support/Plex Media Server/Media/localhost/c/5c58abfd585f7f56c54d3372d811eb765d63c9b.bundle/Contents"
Nov 28, 2018 02:05:04.230 [0x7ff84bdab840] DEBUG - Loaded metadata for Un día de furia (ID 28) in 130.000000 ms
Nov 28, 2018 02:05:04.231 [0x7ff84bdab840] DEBUG - There was a change for metadata item 28 (Un día de furia), saving.
Nov 28, 2018 02:05:04.240 [0x7ff84bdab840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:04.241 [0x7ff84bdab840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:04.249 [0x7ff84bdab840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:04.250 [0x7ff84bdab840] DEBUG - Updating metadata item (save) (Un día de furia) with ID 28
Nov 28, 2018 02:05:04.262 [0x7ff84bdab840] DEBUG - Done with metadata update for 28
Nov 28, 2018 02:05:04.262 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28&metadataType=1&state=5&metadataState=idle
Nov 28, 2018 02:05:04.264 [0x7ff84bdab840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:04.265 [0x7ff84bdab840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:04.267 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=28&metadataType=1&state=5&metadataState=idle
Nov 28, 2018 02:05:04.276 [0x7ff84bdab840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:04.277 [0x7ff84bdab840] DEBUG - Updating metadata item (save) (Un día de furia) with ID 28
Nov 28, 2018 02:05:04.313 [0x7ff84bdab840] DEBUG - Analyzing media parts for item 19 (El pan de la guerra): 32
Nov 28, 2018 02:05:04.313 [0x7ff84bdab840] DEBUG - [ID 32] Media part analysis: /data/movies/peliculas/El Pan De La Guerra.mkv
Nov 28, 2018 02:05:04.313 [0x7ff84bdab840] DEBUG - [MI] Opening input file: "/data/movies/peliculas/El Pan De La Guerra.mkv"
Nov 28, 2018 02:05:04.313 [0x7ff84bdab840] DEBUG - [FFMPEG] - Opening '/data/movies/peliculas/El Pan De La Guerra.mkv' for reading
Nov 28, 2018 02:05:04.313 [0x7ff84bdab840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 28, 2018 02:05:04.342 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Nov 28, 2018 02:05:04.358 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:04.358 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:04.358 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:04.359 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:04.359 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:04.359 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:5 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:04.359 [0x7ff84bdab840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7039 bytes read:35218 seeks:2 nb_streams:6
Nov 28, 2018 02:05:04.360 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 28, 2018 02:05:04.360 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 28, 2018 02:05:04.363 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 28, 2018 02:05:04.363 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 28, 2018 02:05:04.365 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Nov 28, 2018 02:05:04.365 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Nov 28, 2018 02:05:04.365 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Nov 28, 2018 02:05:04.368 [0x7ff84bdab840] DEBUG - [FFMPEG] - no picture 
Nov 28, 2018 02:05:04.372 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:04.374 [0x7ff84bdab840] DEBUG - [FFMPEG] - no picture 
Nov 28, 2018 02:05:04.377 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:04.388 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:04.398 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:04.410 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:04.415 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:04.420 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:04.424 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:04.431 [0x7ff84bdab840] DEBUG - [FFMPEG] - All info found
Nov 28, 2018 02:05:04.431 [0x7ff84bdab840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 65621 bytes read:100754 seeks:2 frames:42
Nov 28, 2018 02:05:04.431 [0x7ff84bdab840] DEBUG - [MI]  * cddaa3b89d815859eb47b5b007f5840d93c3ad94 [mkv h264/ac3] Duration: 5605401ms, 1280x536 (2.388060) 23.976fps, bitrate=3441kbps, audio channels=6, optimizedForStreaming=0 ("/data/movies/peliculas/El Pan De La Guerra.mkv")
Nov 28, 2018 02:05:04.434 [0x7ff84bdab840] DEBUG - [MI] Importing 18 media chapters.
Nov 28, 2018 02:05:04.434 [0x7ff84bdab840] DEBUG - [MI] Chapters had generic titles, clearing.
Nov 28, 2018 02:05:04.440 [0x7ff84bdab840] DEBUG - Doing expensive tags write for 'El pan de la guerra' because something changed.
Nov 28, 2018 02:05:04.491 [0x7ff84bdab840] DEBUG - [MI] Closing input file: "/data/movies/peliculas/El Pan De La Guerra.mkv"
Nov 28, 2018 02:05:04.496 [0x7ff84bdab840] DEBUG - [FFMPEG] - Statistics: 100754 bytes read, 2 seeks
Nov 28, 2018 02:05:04.504 [0x7ff84bdab840] DEBUG - Updating media item 32, size=2411215813, metadata_item_id=19
Nov 28, 2018 02:05:04.504 [0x7ff84bdab840] DEBUG - Updating part with ID=32 [/data/movies/peliculas/El Pan De La Guerra.mkv]
Nov 28, 2018 02:05:04.510 [0x7ff84bdab840] DEBUG - Loading Movie 'El pan de la guerra' XML from /config/Library/Application Support/Plex Media Server/Metadata/Movies/6/6869c576586c662710d033eda6bd70868b3d1f4.bundle/Contents/_combined
Nov 28, 2018 02:05:04.511 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=19&metadataType=1&state=-1&mediaState=analyzing
Nov 28, 2018 02:05:04.518 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=19&metadataType=1&state=-1&mediaState=analyzing
Nov 28, 2018 02:05:04.519 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=19&metadataType=1&state=-1&mediaState=thumbnailing
Nov 28, 2018 02:05:04.520 [0x7ff84bdab840] DEBUG - Item 19 (El pan de la guerra) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 28, 2018 02:05:04.525 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=19&metadataType=1&state=-1&mediaState=thumbnailing
Nov 28, 2018 02:05:04.526 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=19&metadataType=1&state=-1&mediaState=idle
Nov 28, 2018 02:05:04.528 [0x7ff83afff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F435129%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_a64d2a5807dd97802ef5dfbb994215ca5735a6c1
Nov 28, 2018 02:05:04.531 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=19&metadataType=1&state=-1&mediaState=idle
Nov 28, 2018 02:05:04.890 [0x7ff83afff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F435129%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_a64d2a5807dd97802ef5dfbb994215ca5735a6c1
Nov 28, 2018 02:05:04.893 [0x7ff84bdab840] DEBUG - Brought media local (metadata://posters/com.plexapp.agents.themoviedb_a64d2a5807dd97802ef5dfbb994215ca5735a6c1) in 0.1s.
Nov 28, 2018 02:05:04.894 [0x7ff84bdab840] DEBUG - Item 19 (El pan de la guerra) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 28, 2018 02:05:04.901 [0x7ff839fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F435129%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_2b028ac6dfc55193796b5db8469b03f46b97201a
Nov 28, 2018 02:05:05.018 [0x7ff839fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F435129%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_2b028ac6dfc55193796b5db8469b03f46b97201a
Nov 28, 2018 02:05:05.020 [0x7ff84bdab840] DEBUG - Brought media local (metadata://art/com.plexapp.agents.themoviedb_2b028ac6dfc55193796b5db8469b03f46b97201a) in 0.0s.
Nov 28, 2018 02:05:05.055 [0x7ff84bdab840] DEBUG - Loading information from media bundle for part ID 32 from "/config/Library/Application Support/Plex Media Server/Media/localhost/c/ddaa3b89d815859eb47b5b007f5840d93c3ad94.bundle/Contents"
Nov 28, 2018 02:05:05.080 [0x7ff84bdab840] DEBUG - Loaded metadata for El pan de la guerra (ID 19) in 130.000000 ms
Nov 28, 2018 02:05:05.080 [0x7ff84bdab840] DEBUG - There was a change for metadata item 19 (El pan de la guerra), saving.
Nov 28, 2018 02:05:05.082 [0x7ff84bdab840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.083 [0x7ff84bdab840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.091 [0x7ff84bdab840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.092 [0x7ff84bdab840] DEBUG - Updating metadata item (save) (El pan de la guerra) with ID 19
Nov 28, 2018 02:05:05.098 [0x7ff84bdab840] DEBUG - Done with metadata update for 19
Nov 28, 2018 02:05:05.099 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=19&metadataType=1&state=5&metadataState=idle
Nov 28, 2018 02:05:05.099 [0x7ff84bdab840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.101 [0x7ff84bdab840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.103 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=19&metadataType=1&state=5&metadataState=idle
Nov 28, 2018 02:05:05.108 [0x7ff84bdab840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.109 [0x7ff84bdab840] DEBUG - Updating metadata item (save) (El pan de la guerra) with ID 19
Nov 28, 2018 02:05:05.143 [0x7ff84bdab840] DEBUG - It took 10.000000 ms to retrieve 124 items.
Nov 28, 2018 02:05:05.150 [0x7ff84bdab840] DEBUG - Analyzing media parts for item 21 (Ferdinand): 34
Nov 28, 2018 02:05:05.150 [0x7ff84bdab840] DEBUG - [ID 35] Media part analysis: /data/movies/peliculas/Ferdinand.2017.mkv
Nov 28, 2018 02:05:05.150 [0x7ff84bdab840] DEBUG - [MI] Opening input file: "/data/movies/peliculas/Ferdinand.2017.mkv"
Nov 28, 2018 02:05:05.150 [0x7ff84bdab840] DEBUG - [FFMPEG] - Opening '/data/movies/peliculas/Ferdinand.2017.mkv' for reading
Nov 28, 2018 02:05:05.151 [0x7ff84bdab840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 28, 2018 02:05:05.173 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Nov 28, 2018 02:05:05.194 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:05.194 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:05.194 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:05.195 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:05.195 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:05.195 [0x7ff84bdab840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 7153 bytes read:34938 seeks:2 nb_streams:5
Nov 28, 2018 02:05:05.196 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 28, 2018 02:05:05.196 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 28, 2018 02:05:05.198 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 28, 2018 02:05:05.198 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 28, 2018 02:05:05.199 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Nov 28, 2018 02:05:05.199 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Nov 28, 2018 02:05:05.199 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Nov 28, 2018 02:05:05.203 [0x7ff84bdab840] DEBUG - [FFMPEG] - no picture 
Nov 28, 2018 02:05:05.206 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:05.209 [0x7ff84bdab840] DEBUG - [FFMPEG] - no picture 
Nov 28, 2018 02:05:05.211 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:05.218 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:05.227 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:05.239 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:05.244 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:05.251 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:05.257 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:05.265 [0x7ff84bdab840] DEBUG - [FFMPEG] - All info found
Nov 28, 2018 02:05:05.265 [0x7ff84bdab840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 61707 bytes read:100474 seeks:2 frames:42
Nov 28, 2018 02:05:05.265 [0x7ff84bdab840] DEBUG - [MI]  * 2e817c8dbd2b4843a0dc2f53e150a2edf02a0a65 [mkv h264/ac3] Duration: 6486560ms, 1280x536 (2.388060) 23.976fps, bitrate=3663kbps, audio channels=6, optimizedForStreaming=0 ("/data/movies/peliculas/Ferdinand.2017.mkv")
Nov 28, 2018 02:05:05.268 [0x7ff84bdab840] DEBUG - [MI] Importing 24 media chapters.
Nov 28, 2018 02:05:05.268 [0x7ff84bdab840] DEBUG - [MI] Chapters had generic titles, clearing.
Nov 28, 2018 02:05:05.276 [0x7ff84bdab840] DEBUG - Doing expensive tags write for 'Ferdinand' because something changed.
Nov 28, 2018 02:05:05.331 [0x7ff84bdab840] DEBUG - [MI] Closing input file: "/data/movies/peliculas/Ferdinand.2017.mkv"
Nov 28, 2018 02:05:05.337 [0x7ff84bdab840] DEBUG - [FFMPEG] - Statistics: 100474 bytes read, 2 seeks
Nov 28, 2018 02:05:05.343 [0x7ff84bdab840] DEBUG - Updating media item 34, size=2970700010, metadata_item_id=21
Nov 28, 2018 02:05:05.344 [0x7ff84bdab840] DEBUG - Updating part with ID=35 [/data/movies/peliculas/Ferdinand.2017.mkv]
Nov 28, 2018 02:05:05.348 [0x7ff84bdab840] DEBUG - Loading Movie 'Ferdinand' XML from /config/Library/Application Support/Plex Media Server/Metadata/Movies/7/25f5cd0f0ccb625ee193d80597454e8a48c25f4.bundle/Contents/_combined
Nov 28, 2018 02:05:05.349 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=21&metadataType=1&state=-1&mediaState=analyzing
Nov 28, 2018 02:05:05.354 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=21&metadataType=1&state=-1&mediaState=analyzing
Nov 28, 2018 02:05:05.355 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=21&metadataType=1&state=-1&mediaState=thumbnailing
Nov 28, 2018 02:05:05.355 [0x7ff84bdab840] DEBUG - Item 21 (Ferdinand) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 28, 2018 02:05:05.363 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=21&metadataType=1&state=-1&mediaState=thumbnailing
Nov 28, 2018 02:05:05.364 [0x7ff83afff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F364689%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_1216305d3a0580cf5bb096d78980c392a4ddd846
Nov 28, 2018 02:05:05.365 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=21&metadataType=1&state=-1&mediaState=idle
Nov 28, 2018 02:05:05.371 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=21&metadataType=1&state=-1&mediaState=idle
Nov 28, 2018 02:05:05.469 [0x7ff83afff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F364689%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_1216305d3a0580cf5bb096d78980c392a4ddd846
Nov 28, 2018 02:05:05.471 [0x7ff84bdab840] DEBUG - Brought media local (metadata://posters/com.plexapp.agents.themoviedb_1216305d3a0580cf5bb096d78980c392a4ddd846) in 0.0s.
Nov 28, 2018 02:05:05.471 [0x7ff84bdab840] DEBUG - Item 21 (Ferdinand) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 28, 2018 02:05:05.480 [0x7ff839fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F364689%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_dbfce8051d66fbe3823efa85de2ff6af1ce1ba7d
Nov 28, 2018 02:05:05.576 [0x7ff839fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F364689%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_dbfce8051d66fbe3823efa85de2ff6af1ce1ba7d
Nov 28, 2018 02:05:05.578 [0x7ff84bdab840] DEBUG - Brought media local (metadata://art/com.plexapp.agents.themoviedb_dbfce8051d66fbe3823efa85de2ff6af1ce1ba7d) in 0.0s.
Nov 28, 2018 02:05:05.618 [0x7ff84bdab840] DEBUG - Loading information from media bundle for part ID 35 from "/config/Library/Application Support/Plex Media Server/Media/localhost/2/e817c8dbd2b4843a0dc2f53e150a2edf02a0a65.bundle/Contents"
Nov 28, 2018 02:05:05.646 [0x7ff84bdab840] DEBUG - Loaded metadata for Ferdinand (ID 21) in 110.000000 ms
Nov 28, 2018 02:05:05.647 [0x7ff84bdab840] DEBUG - There was a change for metadata item 21 (Ferdinand), saving.
Nov 28, 2018 02:05:05.649 [0x7ff84bdab840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.649 [0x7ff84bdab840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.658 [0x7ff84bdab840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.660 [0x7ff84bdab840] DEBUG - Updating metadata item (save) (Ferdinand) with ID 21
Nov 28, 2018 02:05:05.669 [0x7ff84bdab840] DEBUG - Done with metadata update for 21
Nov 28, 2018 02:05:05.669 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=21&metadataType=1&state=5&metadataState=idle
Nov 28, 2018 02:05:05.670 [0x7ff84bdab840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.671 [0x7ff84bdab840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.675 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=21&metadataType=1&state=5&metadataState=idle
Nov 28, 2018 02:05:05.677 [0x7ff84bdab840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:05.678 [0x7ff84bdab840] DEBUG - Updating metadata item (save) (Ferdinand) with ID 21
Nov 28, 2018 02:05:05.704 [0x7ff84bdab840] DEBUG - Analyzing media parts for item 3 (El ejército de las tinieblas): 3
Nov 28, 2018 02:05:05.705 [0x7ff84bdab840] DEBUG - [ID 33] Media part analysis: /data/movies/peliculas/El ejercito de las tinieblas (1992) (HDRip) .mkv
Nov 28, 2018 02:05:05.705 [0x7ff84bdab840] DEBUG - [MI] Opening input file: "/data/movies/peliculas/El ejercito de las tinieblas (1992) (HDRip) .mkv"
Nov 28, 2018 02:05:05.705 [0x7ff84bdab840] DEBUG - [FFMPEG] - Opening '/data/movies/peliculas/El ejercito de las tinieblas (1992) (HDRip) .mkv' for reading
Nov 28, 2018 02:05:05.705 [0x7ff84bdab840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 28, 2018 02:05:05.707 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Nov 28, 2018 02:05:05.707 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:05.708 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:05.708 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:05.708 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:05.708 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:05.708 [0x7ff84bdab840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6659 bytes read:32768 seeks:0 nb_streams:5
Nov 28, 2018 02:05:05.708 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 28, 2018 02:05:05.709 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 28, 2018 02:05:05.710 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 28, 2018 02:05:05.710 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 28, 2018 02:05:05.711 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Nov 28, 2018 02:05:05.711 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Nov 28, 2018 02:05:05.711 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Nov 28, 2018 02:05:05.716 [0x7ff84bdab840] DEBUG - [FFMPEG] - no picture 
Nov 28, 2018 02:05:05.719 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:05.723 [0x7ff84bdab840] DEBUG - [FFMPEG] - no picture 
Nov 28, 2018 02:05:05.726 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:05.735 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:05.743 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:05.755 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:05.760 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:05.769 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:05.777 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:05.789 [0x7ff84bdab840] DEBUG - [FFMPEG] - All info found
Nov 28, 2018 02:05:05.789 [0x7ff84bdab840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 20336 bytes read:32768 seeks:0 frames:26
Nov 28, 2018 02:05:05.790 [0x7ff84bdab840] DEBUG - [MI]  * d24998b6f501516f604de33f53e0782cd754a49a [mkv h264/ac3] Duration: 5782172ms, 1280x720 (1.777778) 23.976fps, bitrate=3501kbps, audio channels=2, optimizedForStreaming=0 ("/data/movies/peliculas/El ejercito de las tinieblas (1992) (HDRip) .mkv")
Nov 28, 2018 02:05:05.792 [0x7ff84bdab840] DEBUG - [MI] Importing 16 media chapters.
Nov 28, 2018 02:05:05.793 [0x7ff84bdab840] DEBUG - [MI] Chapters had generic titles, clearing.
Nov 28, 2018 02:05:05.798 [0x7ff84bdab840] DEBUG - Doing expensive tags write for 'El ejército de las tinieblas' because something changed.
Nov 28, 2018 02:05:05.843 [0x7ff84bdab840] DEBUG - [MI] Closing input file: "/data/movies/peliculas/El ejercito de las tinieblas (1992) (HDRip) .mkv"
Nov 28, 2018 02:05:05.847 [0x7ff84bdab840] DEBUG - [FFMPEG] - Statistics: 32768 bytes read, 0 seeks
Nov 28, 2018 02:05:05.851 [0x7ff84bdab840] DEBUG - Committing media stream changes for part 33
Nov 28, 2018 02:05:05.856 [0x7ff84bdab840] DEBUG - Updating media item 3, size=2530734596, metadata_item_id=3
Nov 28, 2018 02:05:05.857 [0x7ff84bdab840] DEBUG - Updating part with ID=33 [/data/movies/peliculas/El ejercito de las tinieblas (1992) (HDRip) .mkv]
Nov 28, 2018 02:05:05.862 [0x7ff84bdab840] DEBUG - Loading Movie 'El ejército de las tinieblas' XML from /config/Library/Application Support/Plex Media Server/Metadata/Movies/3/f0a245bddc3812e5df24d1092c617fe68519614.bundle/Contents/_combined
Nov 28, 2018 02:05:05.863 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3&metadataType=1&state=-1&mediaState=analyzing
Nov 28, 2018 02:05:05.871 [0x7ff84bdab840] DEBUG - Item 3 (El ejército de las tinieblas) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 28, 2018 02:05:05.872 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3&metadataType=1&state=-1&mediaState=analyzing
Nov 28, 2018 02:05:05.874 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3&metadataType=1&state=-1&mediaState=thumbnailing
Nov 28, 2018 02:05:05.880 [0x7ff83afff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F766%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_ee170c5e2a9f3e017beb32fb4737b8cd9e3af924
Nov 28, 2018 02:05:05.883 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3&metadataType=1&state=-1&mediaState=thumbnailing
Nov 28, 2018 02:05:05.884 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3&metadataType=1&state=-1&mediaState=idle
Nov 28, 2018 02:05:05.888 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3&metadataType=1&state=-1&mediaState=idle
Nov 28, 2018 02:05:06.011 [0x7ff83afff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F766%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_ee170c5e2a9f3e017beb32fb4737b8cd9e3af924
Nov 28, 2018 02:05:06.013 [0x7ff84bdab840] DEBUG - Brought media local (metadata://posters/com.plexapp.agents.themoviedb_ee170c5e2a9f3e017beb32fb4737b8cd9e3af924) in 0.0s.
Nov 28, 2018 02:05:06.013 [0x7ff84bdab840] DEBUG - Item 3 (El ejército de las tinieblas) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 28, 2018 02:05:06.018 [0x7ff839fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F766%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_992f149a7356bf02cfd1587f9ece9c938d082e52
Nov 28, 2018 02:05:06.185 [0x7ff839fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F766%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_992f149a7356bf02cfd1587f9ece9c938d082e52
Nov 28, 2018 02:05:06.188 [0x7ff84bdab840] DEBUG - Brought media local (metadata://art/com.plexapp.agents.themoviedb_992f149a7356bf02cfd1587f9ece9c938d082e52) in 0.0s.
Nov 28, 2018 02:05:06.211 [0x7ff84bdab840] DEBUG - Loading information from media bundle for part ID 33 from "/config/Library/Application Support/Plex Media Server/Media/localhost/d/24998b6f501516f604de33f53e0782cd754a49a.bundle/Contents"
Nov 28, 2018 02:05:06.239 [0x7ff84bdab840] DEBUG - Loaded metadata for El ejército de las tinieblas (ID 3) in 90.000000 ms
Nov 28, 2018 02:05:06.239 [0x7ff84bdab840] DEBUG - There was a change for metadata item 3 (El ejército de las tinieblas), saving.
Nov 28, 2018 02:05:06.241 [0x7ff84bdab840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:06.241 [0x7ff84bdab840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:06.250 [0x7ff84bdab840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:06.251 [0x7ff84bdab840] DEBUG - Updating metadata item (save) (El ejército de las tinieblas) with ID 3
Nov 28, 2018 02:05:06.258 [0x7ff84bdab840] DEBUG - Done with metadata update for 3
Nov 28, 2018 02:05:06.259 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3&metadataType=1&state=5&metadataState=idle
Nov 28, 2018 02:05:06.260 [0x7ff84bdab840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:06.260 [0x7ff84bdab840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:06.263 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=3&metadataType=1&state=5&metadataState=idle
Nov 28, 2018 02:05:06.268 [0x7ff84bdab840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:06.270 [0x7ff84bdab840] DEBUG - Updating metadata item (save) (El ejército de las tinieblas) with ID 3
Nov 28, 2018 02:05:06.320 [0x7ff84bdab840] DEBUG - It took 20.000000 ms to retrieve 229 items.
Nov 28, 2018 02:05:06.332 [0x7ff84bdab840] DEBUG - Analyzing media parts for item 361 (Animales fantásticos y dónde encontrarlos): 512
Nov 28, 2018 02:05:06.332 [0x7ff84bdab840] DEBUG - [ID 513] Media part analysis: /data/movies/peliculas/Animales fantasticos y donde encontrarlos .mkv
Nov 28, 2018 02:05:06.357 [0x7ff84bdab840] DEBUG - [MI] Opening input file: "/data/movies/peliculas/Animales fantasticos y donde encontrarlos .mkv"
Nov 28, 2018 02:05:06.357 [0x7ff84bdab840] DEBUG - [FFMPEG] - Opening '/data/movies/peliculas/Animales fantasticos y donde encontrarlos .mkv' for reading
Nov 28, 2018 02:05:06.357 [0x7ff84bdab840] DEBUG - [FFMPEG] - Setting default whitelist 'file,crypto'
Nov 28, 2018 02:05:06.358 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format matroska,webm probed with size=2048 and score=100
Nov 28, 2018 02:05:06.359 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:0 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:06.359 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:1 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:06.359 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:2 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:06.359 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:3 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:06.359 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:4 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:06.359 [0x7ff84bdab840] DEBUG - [FFMPEG] - st:5 removing common factor 1000000 from timebase
Nov 28, 2018 02:05:06.360 [0x7ff84bdab840] DEBUG - [FFMPEG] - Before avformat_find_stream_info() pos: 6783 bytes read:35219 seeks:2 nb_streams:6
Nov 28, 2018 02:05:06.360 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 28, 2018 02:05:06.360 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 28, 2018 02:05:06.363 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 28, 2018 02:05:06.363 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 28, 2018 02:05:06.365 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 7, nal_ref_idc: 3
Nov 28, 2018 02:05:06.365 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 8, nal_ref_idc: 3
Nov 28, 2018 02:05:06.365 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 6, nal_ref_idc: 0
Nov 28, 2018 02:05:06.365 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 5, nal_ref_idc: 3
Nov 28, 2018 02:05:06.365 [0x7ff84bdab840] DEBUG - [FFMPEG] - Format yuv420p chosen by get_format().
Nov 28, 2018 02:05:06.369 [0x7ff84bdab840] DEBUG - [FFMPEG] - no picture 
Nov 28, 2018 02:05:06.372 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:06.377 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:06.388 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:06.398 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:06.403 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:06.410 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 0
Nov 28, 2018 02:05:06.417 [0x7ff84bdab840] DEBUG - [FFMPEG] - nal_unit_type: 1, nal_ref_idc: 2
Nov 28, 2018 02:05:06.420 [0x7ff84bdab840] DEBUG - [FFMPEG] - first_dts 292 not matching first dts NOPTS (pts 0, duration 41) in the queue
Nov 28, 2018 02:05:06.435 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 3 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:06.435 [0x7ff84bdab840] WARN - [FFMPEG] - Could not find codec parameters for stream 5 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' and 'probesize' options
Nov 28, 2018 02:05:06.435 [0x7ff84bdab840] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 952123 bytes read:985491 seeks:2 frames:434
Nov 28, 2018 02:05:06.436 [0x7ff84bdab840] DEBUG - [MI]  * 08dc0599bfb9f20c6ca98e938ab104c62562315c [mkv h264/ac3] Duration: 7972352ms, 1280x536 (2.388060) 23.976fps, bitrate=3153kbps, audio channels=6, optimizedForStreaming=0 ("/data/movies/peliculas/Animales fantasticos y donde encontrarlos .mkv")
Nov 28, 2018 02:05:06.438 [0x7ff84bdab840] DEBUG - [MI] Importing 14 media chapters.
Nov 28, 2018 02:05:06.438 [0x7ff84bdab840] DEBUG - [MI] Chapters had generic titles, clearing.
Nov 28, 2018 02:05:06.446 [0x7ff84bdab840] DEBUG - Doing expensive tags write for 'Animales fantásticos y dónde encontrarlos' because something changed.
Nov 28, 2018 02:05:06.498 [0x7ff84bdab840] DEBUG - [MI] Closing input file: "/data/movies/peliculas/Animales fantasticos y donde encontrarlos .mkv"
Nov 28, 2018 02:05:06.505 [0x7ff84bdab840] DEBUG - [FFMPEG] - Statistics: 985491 bytes read, 2 seeks
Nov 28, 2018 02:05:06.513 [0x7ff84bdab840] DEBUG - Updating media item 512, size=3142252219, metadata_item_id=361
Nov 28, 2018 02:05:06.514 [0x7ff84bdab840] DEBUG - Updating part with ID=513 [/data/movies/peliculas/Animales fantasticos y donde encontrarlos .mkv]
Nov 28, 2018 02:05:06.519 [0x7ff84bdab840] DEBUG - Loading Movie 'Animales fantásticos y dónde encontrarlos' XML from /config/Library/Application Support/Plex Media Server/Metadata/Movies/5/c5e6faeef8d96a8ab1f890251cf85c9ccf3d2bc.bundle/Contents/_combined
Nov 28, 2018 02:05:06.519 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=361&metadataType=1&state=-1&mediaState=analyzing
Nov 28, 2018 02:05:06.524 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=361&metadataType=1&state=-1&mediaState=analyzing
Nov 28, 2018 02:05:06.525 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=361&metadataType=1&state=-1&mediaState=thumbnailing
Nov 28, 2018 02:05:06.531 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=361&metadataType=1&state=-1&mediaState=thumbnailing
Nov 28, 2018 02:05:06.532 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=361&metadataType=1&state=-1&mediaState=idle
Nov 28, 2018 02:05:06.533 [0x7ff84bdab840] DEBUG - Item 361 (Animales fantásticos y dónde encontrarlos) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 28, 2018 02:05:06.538 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=361&metadataType=1&state=-1&mediaState=idle
Nov 28, 2018 02:05:06.549 [0x7ff83afff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F259316%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_8aa8ed8eb0c74ac5fa456509d25555fc7e34883a
Nov 28, 2018 02:05:06.740 [0x7ff83afff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F259316%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_8aa8ed8eb0c74ac5fa456509d25555fc7e34883a
Nov 28, 2018 02:05:06.742 [0x7ff84bdab840] DEBUG - Brought media local (metadata://posters/com.plexapp.agents.themoviedb_8aa8ed8eb0c74ac5fa456509d25555fc7e34883a) in 0.0s.
Nov 28, 2018 02:05:06.743 [0x7ff84bdab840] DEBUG - Item 361 (Animales fantásticos y dónde encontrarlos) Scanning metadata graphic elements in XML file ""/Info.xml
Nov 28, 2018 02:05:06.777 [0x7ff839fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F259316%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_84971177f62863d3c9a0c2fb705d683507f0b27d
Nov 28, 2018 02:05:07.091 [0x7ff839fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethemoviedb%3A%2F%2F259316%3Flang%3Des&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Ethemoviedb_84971177f62863d3c9a0c2fb705d683507f0b27d
Nov 28, 2018 02:05:07.094 [0x7ff84bdab840] DEBUG - Brought media local (metadata://art/com.plexapp.agents.themoviedb_84971177f62863d3c9a0c2fb705d683507f0b27d) in 0.0s.
Nov 28, 2018 02:05:07.120 [0x7ff84bdab840] DEBUG - It took 20.000000 ms to retrieve 164 items.
Nov 28, 2018 02:05:07.155 [0x7ff84bdab840] DEBUG - Loading information from media bundle for part ID 513 from "/config/Library/Application Support/Plex Media Server/Media/localhost/0/8dc0599bfb9f20c6ca98e938ab104c62562315c.bundle/Contents"
Nov 28, 2018 02:05:07.182 [0x7ff84bdab840] DEBUG - Loaded metadata for Animales fantásticos y dónde encontrarlos (ID 361) in 160.000000 ms
Nov 28, 2018 02:05:07.182 [0x7ff84bdab840] DEBUG - There was a change for metadata item 361 (Animales fantásticos y dónde encontrarlos), saving.
Nov 28, 2018 02:05:07.183 [0x7ff84bdab840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:07.183 [0x7ff84bdab840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:07.188 [0x7ff84bdab840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:07.190 [0x7ff84bdab840] DEBUG - Updating metadata item (save) (Animales fantásticos y dónde encontrarlos) with ID 361
Nov 28, 2018 02:05:07.200 [0x7ff84bdab840] DEBUG - Done with metadata update for 361
Nov 28, 2018 02:05:07.200 [0x7ff83bfff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=361&metadataType=1&state=5&metadataState=idle
Nov 28, 2018 02:05:07.201 [0x7ff84bdab840] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:07.201 [0x7ff84bdab840] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:07.205 [0x7ff83bfff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=361&metadataType=1&state=5&metadataState=idle
Nov 28, 2018 02:05:07.207 [0x7ff84bdab840] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Nov 28, 2018 02:05:07.208 [0x7ff84bdab840] DEBUG - Updating metadata item (save) (Animales fantásticos y dónde encontrarlos) with ID 361
