MKV movies stutter/buffer after ~40mins | Speed = 10+

vCJv · January 12, 2015, 1:00pm

Problem summary: 1080p movies freeze after ~40mins of play, not every movie, but most. The larger the file (or bitrate) the more likely it is to freeze a few times during the movie. Smaller files (TV shows/short films) don't have a problem. Looking at the logs, the speed is usually around 10-15 (it's only transcoding the audio), yet the cache on the ATV seekbar is only minutes ahead, and then buffers.

Transcoding: MKV files are being transcoded (video direct play, audio transcoded).

My equipment: I have PMS installed on a dedicated PC, which is connected via a powerline plug to the router. My Apple TV 3 is connected via a powerline plug too (as is my Xbox One).

Processor: Intel Core Duo E6550 @ 2.33 GHz
RAM: 4GB
Storage: 1TB internal HDD, 2TB external

ATV model: 3 (latest firmware)
DNS server: 192.168.1.13
Local IP address: 192.168.1.12
Plex media server: Vista (32bit) Service Pack 2
Plex media server version: 0.9.11.7
Local IP address of (PMS): 192.168.1.13

[PlexConnect]
enable_plexgdm = True
ip_pms = 192.168.178.10
port_pms = 32400
enable_dnsserver = True
port_dnsserver = 53
ip_dnsmaster = 8.8.8.8
prevent_atv_update = True
enable_plexconnect_autodetect = True
ip_plexconnect = 0.0.0.0
hosttointercept = trailers.apple.com
port_webserver = 80
enable_webserver_ssl = True
port_ssl = 443
certfile = ./assets/certificates/trailers.pem
loglevel = Normal
logpath = .

Here's part of the log I saved last night, right around the time it froze/buffered a movie:

Jan 11, 2015 20:30:14:698 [4028] DEBUG -  * size => -22
Jan 11, 2015 20:30:14:698 [4028] DEBUG -  * remaining => 25069
Jan 11, 2015 20:30:14:828 [4028] DEBUG - HLS segment range: 0 - 389 average bitrate: 10.67 Mbps (segment size min/avg/max: 1.7/13.3/38.5
Jan 11, 2015 20:30:14:829 [4028] DEBUG - We want 0 segments ahead, last returned was 387 and max is 389.
Jan 11, 2015 20:30:14:829 [4028] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Jan 11, 2015 20:30:15:241 [3228] DEBUG - Request: [127.0.0.1:60760] PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=61.0&size=-22&remaining=-1 (3 live)
Jan 11, 2015 20:30:15:242 [3228] DEBUG -  * progress => 61.0
Jan 11, 2015 20:30:15:242 [3228] DEBUG -  * size => -22
Jan 11, 2015 20:30:15:242 [3228] DEBUG -  * remaining => -1
Jan 11, 2015 20:30:15:367 [3228] DEBUG - HLS segment range: 0 - 389 average bitrate: 10.67 Mbps (segment size min/avg/max: 1.7/13.3/38.5
Jan 11, 2015 20:30:15:367 [3228] DEBUG - We want 0 segments ahead, last returned was 387 and max is 389.
Jan 11, 2015 20:30:15:367 [3228] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Jan 11, 2015 20:30:15:781 [4028] DEBUG - Request: [127.0.0.1:60761] PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=61.0&size=-22&remaining=-1 (3 live)
Jan 11, 2015 20:30:15:781 [4028] DEBUG -  * progress => 61.0
Jan 11, 2015 20:30:15:781 [4028] DEBUG -  * size => -22
Jan 11, 2015 20:30:15:781 [4028] DEBUG -  * remaining => -1
Jan 11, 2015 20:30:15:914 [4028] DEBUG - HLS segment range: 0 - 389 average bitrate: 10.67 Mbps (segment size min/avg/max: 1.7/13.3/38.5
Jan 11, 2015 20:30:15:914 [4028] DEBUG - We want 0 segments ahead, last returned was 387 and max is 389.
Jan 11, 2015 20:30:15:914 [4028] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Jan 11, 2015 20:30:16:006 [5648] DEBUG - Shutting down idle session C0HHC1S4DRHN (idle time is 180 seconds)
Jan 11, 2015 20:30:16:006 [5648] DEBUG - Killing job.
Jan 11, 2015 20:30:16:014 [5648] DEBUG - Job was already killed, not killing again.
Jan 11, 2015 20:30:16:015 [5648] DEBUG - Stopping transcode session C0HHC1S4DRHN
Jan 11, 2015 20:30:16:015 [5648] DEBUG - Cleaning directory for session C0HHC1S4DRHN (C:\Users\DELL\AppData\Local\Temp\plex-transcode-C0HHC1S4DRHN-5b4aaf6f-2ec6-4b67-bd80-1a7f1c57c03c)
Jan 11, 2015 20:30:16:224 [5648] DEBUG - Cleaning old transcode directories.
Jan 11, 2015 20:30:16:225 [5648] DEBUG - Whacked session, 0 remaining.
Jan 11, 2015 20:30:16:225 [5648] DEBUG - Killed 1 idle sessions, and 0 remain.
Jan 11, 2015 20:30:50:861 [1608] DEBUG - [Now] Removing idle session for device C0HHC1S4DRHN.
Jan 11, 2015 20:30:50:861 [1608] DEBUG - Cleaning directory for session C0HHC1S4DRHN (C:\Users\DELL\AppData\Local\Temp\plex-transcode-C0HHC1S4DRHN-0f3ec87b-de83-4882-948c-bc3304b4442a)
Jan 11, 2015 20:30:50:861 [1608] DEBUG - Cleaning directory for session C0HHC1S4DRHN (C:\Users\DELL\AppData\Local\Temp\plex-transcode-C0HHC1S4DRHN-5b4aaf6f-2ec6-4b67-bd80-1a7f1c57c03c)
Jan 11, 2015 20:33:39:416 [3228] DEBUG - Request: [192.168.0.8:52287] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00388.ts (2 live)
Jan 11, 2015 20:33:39:468 [4576] DEBUG - Request: [192.168.0.8:52288] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00389.ts (2 live)
Jan 11, 2015 20:33:39:511 [3228] DEBUG - Request: [192.168.0.8:52289] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00390.ts (2 live)
Jan 11, 2015 20:33:39:564 [4576] DEBUG - Request: [192.168.0.8:52290] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00391.ts (2 live)
Jan 11, 2015 20:33:39:675 [3228] DEBUG - Request: [192.168.0.8:52291] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00392.ts (2 live)
Jan 11, 2015 20:33:39:832 [4576] DEBUG - Request: [192.168.0.8:52292] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00393.ts (2 live)
Jan 11, 2015 20:33:39:922 [3228] DEBUG - Request: [192.168.0.8:52293] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00394.ts (2 live)
Jan 11, 2015 20:33:39:953 [4576] DEBUG - Request: [192.168.0.8:52294] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00395.ts (2 live)

Here's a normal log, of the same movie at the start of the film (no problems as far as I can see. Good speed etc):

Jan 11, 2015 23:06:23:292 [3216] DEBUG - Request: [127.0.0.1:65164] PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=19.4&size=-22&remaining=-1 (5 live)
Jan 11, 2015 23:06:23:292 [3216] DEBUG -  * progress => 19.4
Jan 11, 2015 23:06:23:292 [3216] DEBUG -  * size => -22
Jan 11, 2015 23:06:23:292 [3216] DEBUG -  * remaining => -1
Jan 11, 2015 23:06:23:330 [3216] DEBUG - HLS segment range: 0 - 123 average bitrate: 10.95 Mbps (segment size min/avg/max: 2.3/13.7/31.3
Jan 11, 2015 23:06:23:330 [3216] DEBUG - We want 99 segments ahead, last returned was 24 and max is 123.
Jan 11, 2015 23:06:23:330 [3216] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Jan 11, 2015 23:06:23:331 [3216] VERBOSE - Completed request: PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=19.4&size=-22&remaining=-1: 206
Jan 11, 2015 23:06:23:331 [4300] VERBOSE - Finished writing response for PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=19.4&size=-22&remaining=-1, 0 bytes in 47ms
Jan 11, 2015 23:06:23:352 [1216] DEBUG - [Transcoder] Throttle - Getting back to work.
Jan 11, 2015 23:06:23:353 [4300] VERBOSE - Finished writing response for GET /log?level=3&source=Transcoder&message=Throttle+-+Getting+back+to+work., 0 bytes in 16ms
Jan 11, 2015 23:06:23:667 [3216] DEBUG - Request: [127.0.0.1:65166] PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=19.5&size=-22&speed=10.8&remaining=-1 (5 live)
Jan 11, 2015 23:06:23:667 [3216] DEBUG -  * progress => 19.5
Jan 11, 2015 23:06:23:667 [3216] DEBUG -  * size => -22
Jan 11, 2015 23:06:23:667 [3216] DEBUG -  * speed => 10.8
Jan 11, 2015 23:06:23:667 [3216] DEBUG -  * remaining => -1
Jan 11, 2015 23:06:23:704 [3216] DEBUG - HLS segment range: 0 - 123 average bitrate: 10.95 Mbps (segment size min/avg/max: 2.3/13.7/31.3
Jan 11, 2015 23:06:23:704 [3216] DEBUG - We want 99 segments ahead, last returned was 24 and max is 123.
Jan 11, 2015 23:06:23:704 [3216] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Jan 11, 2015 23:06:23:705 [4300] VERBOSE - Finished writing response for PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=19.5&size=-22&speed=10.8&remaining=-1, 0 bytes in 31ms
Jan 11, 2015 23:06:23:705 [3216] VERBOSE - Completed request: PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=19.5&size=-22&speed=10.8&remaining=-1: 206
Jan 11, 2015 23:06:24:166 [1684] DEBUG - Request: [127.0.0.1:65167] PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=19.6&size=-22&speed=14.5&remaining=416 (5 live)
Jan 11, 2015 23:06:24:166 [1684] DEBUG -  * progress => 19.6
Jan 11, 2015 23:06:24:166 [1684] DEBUG -  * size => -22
Jan 11, 2015 23:06:24:166 [1684] DEBUG -  * speed => 14.5
Jan 11, 2015 23:06:24:166 [1684] DEBUG -  * remaining => 416
Jan 11, 2015 23:06:24:203 [1684] DEBUG - HLS segment range: 0 - 124 average bitrate: 10.99 Mbps (segment size min/avg/max: 2.3/13.7/31.3
Jan 11, 2015 23:06:24:203 [1684] DEBUG - We want 99 segments ahead, last returned was 24 and max is 124.
Jan 11, 2015 23:06:24:203 [1684] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Jan 11, 2015 23:06:24:204 [1684] VERBOSE - Completed request: PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=19.6&size=-22&speed=14.5&remaining=416: 206
Jan 11, 2015 23:06:24:204 [4296] VERBOSE - Finished writing response for PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=19.6&size=-22&speed=14.5&remaining=416, 0 bytes in 31ms
Jan 11, 2015 23:06:24:225 [3216] DEBUG - [Transcoder] Throttle - Going into sloth mode.
Jan 11, 2015 23:06:24:226 [4296] VERBOSE - Finished writing response for GET /log?level=3&source=Transcoder&message=Throttle+-+Going+into+sloth+mode., 0 bytes in 15ms
Jan 11, 2015 23:06:24:527 [1684] DEBUG - Request: [192.168.0.8:52334] GET /:/timeline?ratingKey=7368&key=/library/metadata/7368&duration=6404032&state=playing&time=201402&X-Plex-Client-Identifier=C0HHC1S4DRHN&X-Plex-Device-Name=Apple%20TV (6 live)
Jan 11, 2015 23:06:24:527 [1684] DEBUG -  * ratingKey => 7368
Jan 11, 2015 23:06:24:527 [1684] DEBUG -  * key => /library/metadata/7368
Jan 11, 2015 23:06:24:527 [1684] DEBUG -  * duration => 6404032
Jan 11, 2015 23:06:24:527 [1684] DEBUG -  * state => playing
Jan 11, 2015 23:06:24:527 [1684] DEBUG -  * time => 201402
Jan 11, 2015 23:06:24:527 [1684] DEBUG -  * X-Plex-Client-Identifier => C0HHC1S4DRHN
Jan 11, 2015 23:06:24:527 [1684] DEBUG -  * X-Plex-Device-Name => Apple TV
Jan 11, 2015 23:06:24:528 [1684] DEBUG - Client [C0HHC1S4DRHN] reporting timeline state playing, progress of 201402/6404032ms for guid=, ratingKey=7368 url=, key=/library/metadata/7368, containerKey=, metadataId=7368
Jan 11, 2015 23:06:24:568 [1684] DEBUG - Play progress on 7368 'The Drop' - got played 201402 ms by account 1!
Jan 11, 2015 23:06:24:569 [1684] DEBUG - [Now] Device is  (Apple TV).
Jan 11, 2015 23:06:24:569 [1684] DEBUG - [Now] Updated play state for /library/metadata/7368.

Here's the meta info on the movie shown above:

The below logs are for my Sony TV PlexApp and the Xbox One PlexApp - they aren't relevant to this ATV topic, but thought I'd share anyway.

I then tested the same movie on my Sony TV (Opera) PlexApp, and had buffering/stuttering after seconds. But I assume that's because the video was being transcoded and my PC wasn't fast enough. I understand that.

[spoiler]

Jan 11, 2015 23:19:25:113 [5464] DEBUG - [Now] User is xxxxxx@Xxxxx.com (ID: 1)
Jan 11, 2015 23:19:25:113 [5464] DEBUG - [Now] Device is Opera (Plex for Sony (Opera TV Store)).
Jan 11, 2015 23:19:25:113 [5464] DEBUG - [Now] Updated play state for /library/metadata/7368.
Jan 11, 2015 23:19:25:114 [5464] VERBOSE - Completed request: GET /:/timeline?ratingKey=7368&key=%2Flibrary%2Fmetadata%2F7368&state=playing&time=199950&duration=6404032: 2200
Jan 11, 2015 23:19:25:114 [4300] VERBOSE - Finished writing response for GET /:/timeline?ratingKey=7368&key=%2Flibrary%2Fmetadata%2F7368&state=playing&time=199950&duration=6404032, 0 bytes in 16ms
Jan 11, 2015 23:19:25:116 [1008] DEBUG - Request: [192.168.0.14:37318] GET /video/:/transcode/universal/session/5rgfmeup2kk/base/00217.ts (4 live)
Jan 11, 2015 23:19:25:117 [1008] DEBUG - Asked for segment 217 from session.
Jan 11, 2015 23:19:25:247 [1008] DEBUG - HLS segment range: 0 - 223 average bitrate: 5.68 Mbps (segment size min/avg/max: 0.0/0.7/1.4
Jan 11, 2015 23:19:25:248 [1008] DEBUG - Returning segment 217 from session: C:\Users\DELL\AppData\Local\Temp\plex-transcode-5rgfmeup2kk-0393f49a-d590-4893-9411-672ab68fee15\media-00217.ts
Jan 11, 2015 23:19:25:248 [1008] VERBOSE - [IDLE] Adding (0->1) work item http_download - /video/:/transcode/universal/session/5rgfmeup2kk/base/00217.ts
Jan 11, 2015 23:19:25:248 [1008] VERBOSE - [IDLE] * http_download - /video/:/transcode/universal/session/5rgfmeup2kk/base/00217.ts - 1 active item(s)
Jan 11, 2015 23:19:25:248 [1008] VERBOSE - [IDLE] * transcoder - 5rgfmeup2kk - 1 active item(s)
Jan 11, 2015 23:19:25:248 [1008] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Temp\plex-transcode-5rgfmeup2kk-0393f49a-d590-4893-9411-672ab68fee15\media-00217.ts is 949776.
Jan 11, 2015 23:19:25:249 [1008] VERBOSE - Completed request: GET /video/:/transcode/universal/session/5rgfmeup2kk/base/00217.ts: 2200
Jan 11, 2015 23:19:25:434 [5464] DEBUG - Request: [127.0.0.1:50590] PUT /video/:/transcode/session/5rgfmeup2kk/progress?progress=3.5&size=-22&speed=0.8&remaining=6964 (5 live)
Jan 11, 2015 23:19:25:434 [5464] DEBUG - * progress => 3.5
Jan 11, 2015 23:19:25:434 [5464] DEBUG - * size => -22
Jan 11, 2015 23:19:25:434 [5464] DEBUG - * speed => 0.8
Jan 11, 2015 23:19:25:434 [5464] DEBUG - * remaining => 6964
Jan 11, 2015 23:19:25:499 [5464] DEBUG - HLS segment range: 0 - 223 average bitrate: 5.68 Mbps (segment size min/avg/max: 0.0/0.7/1.4
Jan 11, 2015 23:19:25:499 [5464] DEBUG - We want 999 segments ahead, last returned was 217 and max is 223.
Jan 11, 2015 23:19:25:499 [5464] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Jan 11, 2015 23:19:25:500 [4300] VERBOSE - Finished writing response for PUT /video/:/transcode/session/5rgfmeup2kk/progress?progress=3.5&size=-22&speed=0.8&remaining=6964, 0 bytes in 63ms
Jan 11, 2015 23:19:25:500 [5464] VERBOSE - Completed request: PUT /video/:/transcode/session/5rgfmeup2kk/progress?progress=3.5&size=-22&speed=0.8&remaining=6964: 206
Jan 11, 2015 23:19:25:543 [4300] VERBOSE - Finished writing response for GET /video/:/transcode/universal/session/5rgfmeup2kk/base/00217.ts, 949776 bytes in 437ms
Jan 11, 2015 23:19:25:543 [4300] VERBOSE - [IDLE] Removing (1->0) work item http_download - /video/:/transcode/universal/session/5rgfmeup2kk/base/00217.ts
Jan 11, 2015 23:19:25:543 [4300] VERBOSE - [IDLE] * transcoder - 5rgfmeup2kk - 1 active item(s)
Jan 11, 2015 23:19:25:933 [1008] DEBUG - Request: [127.0.0.1:50591] PUT /video/:/transcode/session/5rgfmeup2kk/progress?progress=3.5&size=-22&speed=1.0&remaining=6933 (4 live)
Jan 11, 2015 23:19:25:933 [1008] DEBUG - * progress => 3.5
Jan 11, 2015 23:19:25:933 [1008] DEBUG - * size => -22
Jan 11, 2015 23:19:25:933 [1008] DEBUG - * speed => 1.0
Jan 11, 2015 23:19:25:933 [1008] DEBUG - * remaining => 6933

[/spoiler]

I then sent the same movie to my Xbox One. The same problem as the Sony app, the movie was stuttering after seconds. But this is due to the PC not being able to handle the video transcode.

[spoiler]

Jan 11, 2015 23:25:22:945 [3996] DEBUG - [Now] User is xxxxx@Xxxxxxxx.com (ID: 1)
Jan 11, 2015 23:25:22:945 [3996] DEBUG - [Now] Device is Xbox One (XBOXONE).
Jan 11, 2015 23:25:22:945 [3996] DEBUG - [Now] Updated play state for /library/metadata/7368.
Jan 11, 2015 23:25:22:946 [4296] VERBOSE - Finished writing response for GET /:/timeline?ratingKey=7368&key=%2Flibrary%2Fmetadata%2F7368&state=playing&time=212903&duration=6404032, 0 bytes in 78ms
Jan 11, 2015 23:25:22:946 [3996] VERBOSE - Completed request: GET /:/timeline?ratingKey=7368&key=%2Flibrary%2Fmetadata%2F7368&state=playing&time=212903&duration=6404032: 2200
Jan 11, 2015 23:25:23:103 [1212] DEBUG - HLS segment range: 0 - 215 average bitrate: 6.08 Mbps (segment size min/avg/max: 0.1/0.8/1.5
Jan 11, 2015 23:25:23:103 [1212] DEBUG - Returning segment 215 from session: C:\Users\DELL\AppData\Local\Temp\plex-transcode-5vh9y9tcv1m2-ef2b2b62-a8ba-426f-b50f-c890cae7f316\media-00215.ts
Jan 11, 2015 23:25:23:104 [1212] VERBOSE - [IDLE] Adding (0->1) work item http_download - /video/:/transcode/universal/session/5vh9y9tcv1m2/base/00215.ts
Jan 11, 2015 23:25:23:104 [1212] VERBOSE - [IDLE] * http_download - /video/:/transcode/universal/session/5vh9y9tcv1m2/base/00214.ts - 1 active item(s)
Jan 11, 2015 23:25:23:104 [1212] VERBOSE - [IDLE] * http_download - /video/:/transcode/universal/session/5vh9y9tcv1m2/base/00215.ts - 1 active item(s)
Jan 11, 2015 23:25:23:104 [1212] VERBOSE - [IDLE] * transcoder - 5vh9y9tcv1m2 - 1 active item(s)
Jan 11, 2015 23:25:23:104 [1212] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Temp\plex-transcode-5vh9y9tcv1m2-ef2b2b62-a8ba-426f-b50f-c890cae7f316\media-00215.ts is 930224.
Jan 11, 2015 23:25:23:106 [1212] VERBOSE - Completed request: GET /video/:/transcode/universal/session/5vh9y9tcv1m2/base/00215.ts: 2200
Jan 11, 2015 23:25:23:126 [1256] DEBUG - Request: [127.0.0.1:51406] PUT /video/:/transcode/session/5vh9y9tcv1m2/progress?progress=3.4&size=-22&speed=1.0&remaining=6287 (9 live)
Jan 11, 2015 23:25:23:126 [1256] DEBUG - * progress => 3.4
Jan 11, 2015 23:25:23:126 [1256] DEBUG - * size => -22
Jan 11, 2015 23:25:23:126 [1256] DEBUG - * speed => 1.0
Jan 11, 2015 23:25:23:126 [1256] DEBUG - * remaining => 6287
Jan 11, 2015 23:25:23:127 [3064] VERBOSE - [IDLE] Removing (1->0) work item http_download - /video/:/transcode/universal/session/5vh9y9tcv1m2/base/00214.ts
Jan 11, 2015 23:25:23:127 [3064] VERBOSE - [IDLE] * http_download - /video/:/transcode/universal/session/5vh9y9tcv1m2/base/00215.ts - 1 active item(s)
Jan 11, 2015 23:25:23:127 [3064] VERBOSE - [IDLE] * transcoder - 5vh9y9tcv1m2 - 1 active item(s)
Jan 11, 2015 23:25:23:186 [1256] DEBUG - HLS segment range: 0 - 215 average bitrate: 6.08 Mbps (segment size min/avg/max: 0.1/0.8/1.5
Jan 11, 2015 23:25:23:186 [1256] DEBUG - We want 999 segments ahead, last returned was 215 and max is 215.
Jan 11, 2015 23:25:23:186 [1256] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Jan 11, 2015 23:25:23:187 [4296] VERBOSE - Finished writing response for PUT /video/:/transcode/session/5vh9y9tcv1m2/progress?progress=3.4&size=-22&speed=1.0&remaining=6287, 0 bytes in 62ms
Jan 11, 2015 23:25:23:187 [1256] VERBOSE - Completed request: PUT /video/:/transcode/session/5vh9y9tcv1m2/progress?progress=3.4&size=-22&speed=1.0&remaining=6287: 206
Jan 11, 2015 23:25:23:389 [4300] VERBOSE - Finished writing response for GET /video/:/transcode/universal/session/5vh9y9tcv1m2/base/00215.ts, 930224 bytes in 1077ms
Jan 11, 2015 23:25:23:423 [3064] DEBUG - Request: [192.168.0.6:51346] GET /video/:/transcode/universal/session/5vh9y9tcv1m2/base/00216.ts (9 live)
Jan 11, 2015 23:25:23:423 [3064] DEBUG - Asked for segment 216 from session.
Jan 11, 2015 23:25:23:486 [3064] DEBUG - HLS segment range: 0 - 215 average bitrate: 6.08 Mbps (segment size min/avg/max: 0.1/0.8/1.5
Jan 11, 2015 23:25:23:630 [1256] DEBUG - Request: [127.0.0.1:51407] PUT /video/:/transcode/session/5vh9y9tcv1m2/progress?progress=3.4&size=-22&speed=1.0&remaining=6206 (8 live)
Jan 11, 2015 23:25:23:631 [1256] DEBUG - * progress => 3.4
Jan 11, 2015 23:25:23:631 [1256] DEBUG - * size => -22
Jan 11, 2015 23:25:23:631 [1256] DEBUG - * speed => 1.0
Jan 11, 2015 23:25:23:631 [1256] DEBUG - * remaining => 6206
Jan 11, 2015 23:25:23:670 [3064] DEBUG - HLS segment range: 0 - 215 average bitrate: 6.08 Mbps (segment size min/avg/max: 0.1/0.8/1.5
Jan 11, 2015 23:25:23:690 [1256] DEBUG - HLS segment range: 0 - 215 average bitrate: 6.08 Mbps (segment size min/avg/max: 0.1/0.8/1.5
Jan 11, 2015 23:25:23:690 [1256] DEBUG - We want 999 segments ahead, last returned was 215 and max is 215.
Jan 11, 2015 23:25:23:690 [1256] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.

[/spoiler]

So I'm wondering, is my Server at fault (even though it's doing a fine job at transcoding audio for ATV?) Or is my ATV having trouble receiving the file?

I hope I've provided enough info, please help :)

jmcgeejr · January 12, 2015, 1:34pm

Sadly that cpu has a passmark of 1500 and plex suggests 2000 for a minimum for 1080p, if you can keep your bitrate up high enough to the client then it would only be a container change and an audio transcode which is probably why youre finding it hit and miss (at certain points during the movie the cpu just cant keep up. Is your ATV hardwired in? If so I have pushed up to 40Mbps to it and had no issues.

vCJv · January 12, 2015, 1:59pm

Sadly that cpu has a passmark of 1500 and plex suggests 2000 for a minimum for 1080p, if you can keep your bitrate up high enough to the client then it would only be a container change and an audio transcode which is probably why youre finding it hit and miss (at certain points during the movie the cpu just cant keep up. Is your ATV hardwired in? If so I have pushed up to 40Mbps to it and had no issues.

Thanks for the response.

The ATV is hardwired (well, powerlines/homeplug).

Even though the speed is 10-15, the CPU is still causing the problem? I know it's a poor CPU, but I didn't think it would use much of it for just audio transcoding?

jmcgeejr · January 12, 2015, 2:02pm

The speed will change depending on the complexity of that part of the file, as you get farther in you may have more complexity in the file and the cpu cant keep up. Are you sure you're only transocoding audio? What do you have your local playrate set to in plexconnect on your atv3?

vCJv · January 12, 2015, 2:10pm

The speed will change depending on the complexity of that part of the file, as you get farther in you may have more complexity in the file and the cpu cant keep up. Are you sure you're only transocoding audio? What do you have your local playrate set to in plexconnect on your atv3?

I have it set to Automatic. If I try direct, it won't play any file.

When I view the PMS on the server, it states:

Transcoding

Video: Direct Play

Audio: Transcoding AC3

That's what made me assume the video was being left untouched.

jmcgeejr · January 12, 2015, 2:14pm

Try setting to 1080p 40Mbps in plexconnect setting, yes that does seem to be direct playing video content but just to be sure.

RageInvader · January 13, 2015, 8:56am

Do a speed test on your home plugs.... I bought 300mbps ones with Gigabit ethernet ports, quite expensive but could only achieve about 19Mbps from one room to the other in my flat.

Caused me a lot of headaches trying to get the Apple TV to stream from my server. Ended up putting it in the Server in kitchen with cable going through the door then under the rug to my TV.

The homeplugs may have been a nightmare for me as I live in a flat and there will be loads of electronics plugged in in near vicinity, possibly even someone using same type of home plugs. But do a speed test (I used BlackMagic, by setting it to the network share).

vCJv · January 13, 2015, 10:38am

Do a speed test on your home plugs.... I bought 300mbps ones with Gigabit ethernet ports, quite expensive but could only achieve about 19Mbps from one room to the other in my flat.

Caused me a lot of headaches trying to get the Apple TV to stream from my server. Ended up putting it in the Server in kitchen with cable going through the door then under the rug to my TV.

The homeplugs may have been a nightmare for me as I live in a flat and there will be loads of electronics plugged in in near vicinity, possibly even someone using same type of home plugs. But do a speed test (I used BlackMagic, by setting it to the network share).

I haven't thought of that. But wouldn't the movie struggle to share at all, as opposed to half way in?

Last night I turned my Laptop (i7-4700MQ) into a PMS, and tried streaming to my Xbox One. It ran smoothly, but I didn't have time to stream the whole movie to my ATV.

BlackMagic, is this a MAC software? Is there a Windows equivalent?

RageInvader · January 13, 2015, 10:47am

Problem with homeplugs is anything plugged into the mains can cause interference... The bit that confused me the most was that the homeplugs worked to start with, then I got random buffering, some files buffering every two seconds some only halfway through... I then had the problem with plex web knowing I had a slow connection forcing low quality and transcoding which I know my server cannot do.

not sure on windows equivalent software... I know every test except having the homeplugs in the same wall plug gave me terrible speeds.

CusmaR01 · January 13, 2015, 2:04pm

i've had the same problem a couple of times but with different fixes, although i have a much faster cpu. (i5 @ 4,5GHz)

First my problem was poor network speed that caused stuttering, and thats what i think you have since home plugs is really bad most of the time, they may have the "up to 300mbit" on the box but it aint going near that, my friend got between 8-10mbit with his.

The other time i had problems with freezes i fixed by changing the transcoder temp folder to another harddrive, dont know why but halfway through the movies plex got write permission errors and therefor crashed.

hope this helps!

vCJv · January 13, 2015, 3:20pm

Thanks for the feedback.

The PC is currently in the detached garage (a wife friendly location) using the homeplug.

I use speedtest.net for internet speed, which the PC is consistently getting 17mb (the same as the other devices in the house). Is there an easier way to check LAN speed (other than sending a video file over the network)?

I'll test this tonight, and move the PC indoors and test without a homeplug too.

As for CPU, it's not being used at all (even though it's incredibly poor). I will happily upgrade the CPU if that turns out to be the cause.

vCJv · January 13, 2015, 8:05pm

I have an internal 1TB and external 2TB, both with media on that the PMS sees.

When copying a 7.64GB file:

From internal HDD to an external HDD: 32 MB/sec

Over LAN (using Powerlines/homeplug): 8 MB/sec (64mbps?)

I then moved the PC indoors.

Over LAN (hardwired straight to router): 11 MB/sec (88mbps?)

My Powerlines/homeplugs are 200mbps (I believe that's 25MB/sec?), and my router is 10/100mbps, maybe that's the bottleneck, I could add a Gigabit switch in and route all LAN through that. See if the router is the problem.

Please correct me if I'm way off the mark here...

So in theory, if the movie above is 7.64GB (and it's 106mins long), that would need a LAN transfer rate of 1.2MBps (or 9.6mbps) to stream it smoothly. Is that correct?

pecinko · January 13, 2015, 8:48pm

I would move that pc near atv and use Ethernet to test connection. I would not invest in network equipment before making sure CPU can handle it’s job.

Second thing is installing pms on your laptop - if it plays smoothly over power lines then you would know Cpu Is to blame.

vCJv · January 13, 2015, 10:01pm

I would move that pc near atv and use Ethernet to test connection. I would not invest in network equipment before making sure CPU can handle it's job.

Second thing is installing pms on your laptop - if it plays smoothly over power lines then you would know Cpu Is to blame.

My test above, I've already moved the PC indoors and Ethernet connected it (PC > Router > ATV). I can borrow a switch from work for testing purposes.

I've tested my laptop as the PMS and it streams flawlessly to the Xbox and Sony TV app.

The thing I can't understand is, if ATV is only needing the audio transcoded, and the speed is 10-15, surely the CPU on the PC is fast enough? And it never stutters, it take 40-60 mins before it just stops.

It's this log I don't understand. Why has it killed the transcode?

Jan 11, 2015 20:30:15:781 [4028] DEBUG - Request: [127.0.0.1:60761] PUT /video/:/transcode/session/C0HHC1S4DRHN/progress?progress=61.0&size=-22&remaining=-1 (3 live)
Jan 11, 2015 20:30:15:781 [4028] DEBUG -  * progress => 61.0
Jan 11, 2015 20:30:15:781 [4028] DEBUG -  * size => -22
Jan 11, 2015 20:30:15:781 [4028] DEBUG -  * remaining => -1
Jan 11, 2015 20:30:15:914 [4028] DEBUG - HLS segment range: 0 - 389 average bitrate: 10.67 Mbps (segment size min/avg/max: 1.7/13.3/38.5
Jan 11, 2015 20:30:15:914 [4028] DEBUG - We want 0 segments ahead, last returned was 387 and max is 389.
Jan 11, 2015 20:30:15:914 [4028] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Jan 11, 2015 20:30:16:006 [5648] DEBUG - Shutting down idle session C0HHC1S4DRHN (idle time is 180 seconds)
Jan 11, 2015 20:30:16:006 [5648] DEBUG - Killing job.
Jan 11, 2015 20:30:16:014 [5648] DEBUG - Job was already killed, not killing again.
Jan 11, 2015 20:30:16:015 [5648] DEBUG - Stopping transcode session C0HHC1S4DRHN
Jan 11, 2015 20:30:16:015 [5648] DEBUG - Cleaning directory for session C0HHC1S4DRHN (C:\Users\DELL\AppData\Local\Temp\plex-transcode-C0HHC1S4DRHN-5b4aaf6f-2ec6-4b67-bd80-1a7f1c57c03c)
Jan 11, 2015 20:30:16:224 [5648] DEBUG - Cleaning old transcode directories.
Jan 11, 2015 20:30:16:225 [5648] DEBUG - Whacked session, 0 remaining.
Jan 11, 2015 20:30:16:225 [5648] DEBUG - Killed 1 idle sessions, and 0 remain.
Jan 11, 2015 20:30:50:861 [1608] DEBUG - [Now] Removing idle session for device C0HHC1S4DRHN.
Jan 11, 2015 20:30:50:861 [1608] DEBUG - Cleaning directory for session C0HHC1S4DRHN (C:\Users\DELL\AppData\Local\Temp\plex-transcode-C0HHC1S4DRHN-0f3ec87b-de83-4882-948c-bc3304b4442a)
Jan 11, 2015 20:30:50:861 [1608] DEBUG - Cleaning directory for session C0HHC1S4DRHN (C:\Users\DELL\AppData\Local\Temp\plex-transcode-C0HHC1S4DRHN-5b4aaf6f-2ec6-4b67-bd80-1a7f1c57c03c)
Jan 11, 2015 20:33:39:416 [3228] DEBUG - Request: [192.168.0.8:52287] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00388.ts (2 live)
Jan 11, 2015 20:33:39:468 [4576] DEBUG - Request: [192.168.0.8:52288] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00389.ts (2 live)
Jan 11, 2015 20:33:39:511 [3228] DEBUG - Request: [192.168.0.8:52289] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00390.ts (2 live)
Jan 11, 2015 20:33:39:564 [4576] DEBUG - Request: [192.168.0.8:52290] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00391.ts (2 live)
Jan 11, 2015 20:33:39:675 [3228] DEBUG - Request: [192.168.0.8:52291] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00392.ts (2 live)
Jan 11, 2015 20:33:39:832 [4576] DEBUG - Request: [192.168.0.8:52292] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00393.ts (2 live)
Jan 11, 2015 20:33:39:922 [3228] DEBUG - Request: [192.168.0.8:52293] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00394.ts (2 live)
Jan 11, 2015 20:33:39:953 [4576] DEBUG - Request: [192.168.0.8:52294] GET /video/:/transcode/universal/session/C0HHC1S4DRHN/base-ac3/00395.ts (2 live)

baa · January 14, 2015, 6:25am

Version of PlexConnect?

You actually should see "transcoder pings" in the PMS log. I didn't see any - are they cut away?

vCJv · January 14, 2015, 9:50pm

Version of PlexConnect?

You actually should see "transcoder pings" in the PMS log. I didn't see any - are they cut away?

Hi Baa, I've not edited the code I pasted above, should the pings be between the lines?

Version of PlexConnect is 0.4-dev.

Here's a log from tonight. I tried copying to pastebin but it was too large. It's using the same movie, same PMS machine and same ATV. I cancelled it after a few minutes:

[spoiler]

Jan 14, 2015 21:31:24:675 [4320] INFO - LANGID = 0x409
Jan 14, 2015 21:31:24:677 [4320] INFO - Windows version: 6.0 (Build 6002)
Jan 14, 2015 21:31:24:678 [4320] INFO - 2 2327 MHz processor(s): Architecture=0, Level=6, Revision=3851
Jan 14, 2015 21:31:24:678 [4320] INFO - PROCESSOR_IDENTIFIER=x86 Family 6 Model 15 Stepping 11, GenuineIntel
Jan 14, 2015 21:31:24:678 [4320] INFO - Plex Media Server v0.9.11.7.803-87d0708 - Microsoft PC  - build: windows-i386 english
Jan 14, 2015 21:31:24:678 [4320] DEBUG - Opening 20 database sessions to library, SQLite 3.8.6, threadsafe=1
Jan 14, 2015 21:31:24:734 [4320] DEBUG - Running migrations.
Jan 14, 2015 21:31:24:735 [4320] DEBUG - Running 5 database fix-ups.
Jan 14, 2015 21:31:25:802 [4320] DEBUG - Listening on port 32400 with queue of 2147483647 connections.
Jan 14, 2015 21:31:25:812 [4320] DEBUG - Listening on port 32401 with queue of 2147483647 connections.
Jan 14, 2015 21:31:25:834 [4320] DEBUG - Setting up scheduled updates
Jan 14, 2015 21:31:25:834 [4320] INFO - Refreshing paths watched by LibraryUpdateManager
Jan 14, 2015 21:31:25:834 [5328] DEBUG - Starting scheduled updates, every 86400 seconds
Jan 14, 2015 21:31:25:835 [4320] DEBUG - LibraryUpdateManager will start watching D:\Movies
Jan 14, 2015 21:31:25:835 [4320] DEBUG - LibraryUpdateManager will start watching F:\Movies
Jan 14, 2015 21:31:25:835 [4320] DEBUG - LibraryUpdateManager will start watching C:\Users\DELL\Music\iTunes\iTunes Media\Music
Jan 14, 2015 21:31:25:835 [4320] DEBUG - LibraryUpdateManager will start watching F:\TV Shows
Jan 14, 2015 21:31:25:869 [1244] DEBUG - File C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins changed: -1 => 1421225102.
Jan 14, 2015 21:31:25:869 [1244] DEBUG - Scanning for plug-ins in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins
Jan 14, 2015 21:31:25:873 [1244] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\System.bundle.
Jan 14, 2015 21:31:25:873 [1244] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Jan 14, 2015 21:31:25:878 [1244] DEBUG - Starting file watcher for com.plexapp.system
Jan 14, 2015 21:31:25:880 [1244] DEBUG - Plugin::createProcess: Creating plugin process: "C:\Program Files\Plex\Plex Media Server\PlexScriptHost.exe" "C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\Framework.bundle\Contents\Resources\Versions\2\Python/bootstrap.py" "C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\System.bundle"
Jan 14, 2015 21:31:26:149 [1244] DEBUG - Spawned plug-in com.plexapp.system with PID 2616
Jan 14, 2015 21:31:26:149 [1244] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes
Jan 14, 2015 21:31:29:735 [3484] DEBUG - Crash Reporter: Starting up.

Jan 14, 2015 21:31:30:198 [1244] DEBUG - Reading 486 bytes in the body, code is 200

Jan 14, 2015 21:31:30:198 [1244] DEBUG - [com.plexapp.system] Plug-in running on port 55148.

Jan 14, 2015 21:31:30:198 [1244] DEBUG -  * Plug-in handles prefix: /system

Jan 14, 2015 21:31:30:198 [1244] DEBUG -  * Plug-in handles prefix: /player

Jan 14, 2015 21:31:30:198 [1244] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes

Jan 14, 2015 21:31:30:244 [1244] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.

Jan 14, 2015 21:31:30:244 [1244] DEBUG - Updating the list of agents known by the system.

Jan 14, 2015 21:31:30:244 [1244] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents

Jan 14, 2015 21:31:30:245 [1244] DEBUG - HTTP requesting to: http://127.0.0.1:55148/system/agents

Jan 14, 2015 21:31:30:283 [4320] DEBUG - Found iTunes library at C:\Users\DELL\Music\iTunes\iTunes Music Library.xml

Jan 14, 2015 21:31:30:283 [4320] DEBUG - Found 1 library files

Jan 14, 2015 21:31:30:283 [4320] DEBUG - Found genres file at C:\Program Files\iTunes\iTunes.Resources\genres.plist

Jan 14, 2015 21:31:30:302 [4320] DEBUG - Found 103 genre icons

Jan 14, 2015 21:31:30:338 [4320] DEBUG - iTunes: There are 5444 audio tracks and 641 video tracks.

Jan 14, 2015 21:31:30:338 [4320] DEBUG - Image transcode cache directory: C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder

Jan 14, 2015 21:31:30:339 [4320] DEBUG - Cleaning old transcode directories.

Jan 14, 2015 21:31:30:359 [4320] DEBUG - MyPlex: Updating device connections (from timer: 0)

Jan 14, 2015 21:31:30:360 [4320] DEBUG - HTTP requesting to: https://plex.tv/devices/5a4e419d0389410a5dc62fbb1842c61a76dbaf9a?Connection[][uri]=http://192.168.0.13:32400&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx

Jan 14, 2015 21:31:31:167 [4320] DEBUG - NAT: Starting Mapping Thread.

Jan 14, 2015 21:31:31:167 [4320] DEBUG - NAT: Using previously obtained port of 20191.

Jan 14, 2015 21:31:31:167 [4320] DEBUG - NAT: Creating NAT puncher thread 03CFE660

Jan 14, 2015 21:31:31:167 [4320] DEBUG - NetworkInterface: Notified of network changed (force=0)

Jan 14, 2015 21:31:31:170 [2708] DEBUG - HTTP requesting to: https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx

Jan 14, 2015 21:31:31:172 [6088] DEBUG - NAT: Updating mapping in puncher thread 03CFE660

Jan 14, 2015 21:31:31:172 [6088] DEBUG - NAT: Creating puncher.

Jan 14, 2015 21:31:31:172 [6088] DEBUG - NAT: Switching to NAT-PMP strategy.

Jan 14, 2015 21:31:31:172 [6088] DEBUG - NAT: Creating NATPmPPuncher.

Jan 14, 2015 21:31:31:172 [6088] DEBUG - NAT: Initializing NAT-PMP, success=1

Jan 14, 2015 21:31:31:172 [6088] DEBUG - NAT: Finding public address failed the first way, switching.

Jan 14, 2015 21:31:31:172 [6088] DEBUG - NAT: Switching to UPnP strategy.

Jan 14, 2015 21:31:31:172 [6088] DEBUG - NAT: Creating NATUPnPPuncher.

Jan 14, 2015 21:31:31:209 [4320] DEBUG - Network interfaces:

Jan 14, 2015 21:31:31:209 [4320] DEBUG -  * 1 {3D4E88D4-6A70-11DB-B1BA-806E6F6E6963} (127.0.0.1) (loopback: 1)

Jan 14, 2015 21:31:31:209 [4320] DEBUG -  * 10 {7A280804-33E7-4E28-9819-61B2366E33FF} (192.168.0.13) (loopback: 0)

Jan 14, 2015 21:31:31:950 [1236] DEBUG - Sync: myPlexSignIn

Jan 14, 2015 21:31:31:950 [2708] DEBUG - EventSource: Creating.

Jan 14, 2015 21:31:31:950 [2708] DEBUG - EventSource: Connecting to pubsub.plex.tv

Jan 14, 2015 21:31:31:959 [2708] DEBUG - MyPlex: Last published value didn’t change, we’re done.

Jan 14, 2015 21:31:31:960 [2708] DEBUG - HTTP requesting to: https://plex.tv/devices/5a4e419d0389410a5dc62fbb1842c61a76dbaf9a/certificate?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx

Jan 14, 2015 21:31:31:979 [2560] DEBUG - It took 0.001000 sec to serialize a list with 4 elements.

Jan 14, 2015 21:31:31:979 [2560] DEBUG - MyPlex: Sending Server Info to myPlex (user=xxxx@xxxxxxxxx.com, ip=, port=0)

Jan 14, 2015 21:31:31:980 [2560] DEBUG - HTTP requesting to: https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx

Jan 14, 2015 21:31:32:471 [1244] DEBUG - [com.plexapp.system] HTTP reply status 200, with 5773 bytes of content.

Jan 14, 2015 21:31:32:474 [1244] DEBUG - The system knows about 11 agents.

Jan 14, 2015 21:31:32:474 [1244] DEBUG - Waiting for all 18 plug-ins to finish starting.

Jan 14, 2015 21:31:32:475 [5280] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\Framework.bundle.

Jan 14, 2015 21:31:32:476 [5280] VERBOSE - The plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\Framework.bundle is not runnable.

Jan 14, 2015 21:31:32:476 [5280] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\HTbackdrops.bundle.

Jan 14, 2015 21:31:32:479 [5280] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.htbackdrops] yet.

Jan 14, 2015 21:31:32:479 [5280] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\IMDB.bundle.

Jan 14, 2015 21:31:32:481 [4372] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\LastFM.bundle.

Jan 14, 2015 21:31:32:481 [5280] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.imdb] yet.

Jan 14, 2015 21:31:32:481 [5280] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\LocalMedia.bundle.

Jan 14, 2015 21:31:32:484 [4372] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.lastfm] yet.

Jan 14, 2015 21:31:32:484 [4372] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\Media-Flags.bundle.

Jan 14, 2015 21:31:32:485 [5280] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.localmedia] yet.

Jan 14, 2015 21:31:32:485 [4372] VERBOSE - The plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\Media-Flags.bundle is not runnable.

Jan 14, 2015 21:31:32:485 [5280] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\MoviePosterDB.bundle.

Jan 14, 2015 21:31:32:485 [4372] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\PersonalMedia.bundle.

Jan 14, 2015 21:31:32:487 [5280] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.movieposterdb] yet.

Jan 14, 2015 21:31:32:487 [5280] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\PlexThemeMusic.bundle.

Jan 14, 2015 21:31:32:487 [4372] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.none] yet.

Jan 14, 2015 21:31:32:487 [4372] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\Scanners.bundle.

Jan 14, 2015 21:31:32:488 [4372] VERBOSE - The plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\Scanners.bundle is not runnable.

Jan 14, 2015 21:31:32:488 [4372] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.

Jan 14, 2015 21:31:32:489 [4372] VERBOSE - The plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\Services.bundle is not runnable.

Jan 14, 2015 21:31:32:489 [5280] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.plexthememusic] yet.

Jan 14, 2015 21:31:32:489 [5280] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\SiteConfigurations.bundle.

Jan 14, 2015 21:31:32:489 [4372] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\The-Movie-Database.bundle.

Jan 14, 2015 21:31:32:490 [5280] VERBOSE - The plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\SiteConfigurations.bundle is not runnable.

Jan 14, 2015 21:31:32:490 [5280] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\TheTVDB.bundle.

Jan 14, 2015 21:31:32:491 [4372] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.themoviedb] yet.

Jan 14, 2015 21:31:32:492 [4372] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\WebClient.bundle.

Jan 14, 2015 21:31:32:492 [5280] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.thetvdb] yet.

Jan 14, 2015 21:31:32:492 [5280] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\WebManager.bundle.

Jan 14, 2015 21:31:32:492 [4372] VERBOSE - The plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\WebClient.bundle is not runnable.

Jan 14, 2015 21:31:32:493 [4372] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\Wikipedia.bundle.

Jan 14, 2015 21:31:32:493 [5280] VERBOSE - The plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\WebManager.bundle is not runnable.

Jan 14, 2015 21:31:32:494 [5756] DEBUG - Starting plug-in C:\Users\DELL\AppData\Local\Plex Media Server\Plug-ins\OpenSubtitles.bundle.

Jan 14, 2015 21:31:32:495 [4372] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.wikipedia] yet.

Jan 14, 2015 21:31:32:496 [5756] DEBUG - Perfect cache hit, we don’t need to start [com.plexapp.agents.opensubtitles] yet.

Jan 14, 2015 21:31:32:574 [1244] DEBUG - Done waiting for all plug-ins to start.

Jan 14, 2015 21:31:32:574 [1244] DEBUG - Creating NetworkServices singleton.

Jan 14, 2015 21:31:32:575 [1236] DEBUG - HTTP requesting to: https://plex.tv/servers/5a4e419d0389410a5dc62fbb1842c61a76dbaf9a/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx

Jan 14, 2015 21:31:33:326 [1236] DEBUG - Sync: downloaded 0 sync lists, analyzing now…

Jan 14, 2015 21:31:33:326 [1236] INFO - Sync: analyzed 0 sync list(s) with 0 sync items(s)

Jan 14, 2015 21:31:33:951 [2560] DEBUG - MyPlex: Published Mapping State response was 422

Jan 14, 2015 21:31:33:951 [2560] WARN - MyPlex: Invalid response when mapping state (code=422):

Jan 14, 2015 21:31:34:669 [6088] DEBUG - NAT: Initialized UPnP, result: SUCCESS

Jan 14, 2015 21:31:34:880 [6088] DEBUG - NAT: Requested public address via UPnP (success=1)

Jan 14, 2015 21:31:34:880 [6088] DEBUG - NAT: Obtained public address of 90.200.72.35 (failed: 0 myPlex: 0).

Jan 14, 2015 21:31:34:880 [6088] DEBUG - NAT: Checking for change in address: old was  and new is 90.200.72.35.

Jan 14, 2015 21:31:35:199 [6088] DEBUG - NAT: Unmapped port via UPnP.

Jan 14, 2015 21:31:35:742 [6088] DEBUG - NAT: Added mapping via UPnP, Public Side => 90.200.72.35:20191 (TCP), Private Side => 192.168.0.13:32400

Jan 14, 2015 21:31:35:742 [6088] INFO - NAT: Mapping changed 90.200.72.35:20191 → 192.168.0.13:32400

Jan 14, 2015 21:31:35:753 [6088] DEBUG - It took 0.001000 sec to serialize a list with 4 elements.

Jan 14, 2015 21:31:35:753 [6088] DEBUG - MyPlex: Sending Server Info to myPlex (user=xxxx@xxxxxxxxx.com, ip=, port=20191)

Jan 14, 2015 21:31:35:754 [6088] DEBUG - HTTP requesting to: https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx

Jan 14, 2015 21:31:36:704 [6088] DEBUG - MyPlex: Published Mapping State response was 201

Jan 14, 2015 21:31:36:705 [6088] DEBUG - MyPlex: Got response for 5a4e419d0389410a5dc62fbb1842c61a76dbaf9a

Jan 14, 2015 21:31:36:705 [6088] DEBUG - MyPlex: Registered IP: 90.200.72.35

Jan 14, 2015 21:31:36:705 [6088] DEBUG - MyPlex: Registered Port: 20191

Jan 14, 2015 21:31:36:711 [6088] DEBUG - MyPlex: Last published value didn’t change, we’re done.

Jan 14, 2015 21:31:36:712 [6088] DEBUG - NAT: Updating mapping complete in thread 03CFE660.

Jan 14, 2015 21:31:36:712 [6088] DEBUG - NAT: Deleting puncher.

Jan 14, 2015 21:31:36:712 [6088] DEBUG - NAT: Deleting NATUPnPPuncher.

Jan 14, 2015 21:31:36:712 [6088] DEBUG - NAT: Deleting NATPmPPuncher.

Jan 14, 2015 21:31:36:712 [4320] DEBUG - NAT: Updating mapping in puncher thread 03CFE660

Jan 14, 2015 21:31:36:712 [4320] DEBUG - NAT: Creating puncher.

Jan 14, 2015 21:31:36:712 [4320] DEBUG - NAT: Switching to NAT-PMP strategy.

Jan 14, 2015 21:31:36:712 [4320] DEBUG - NAT: Creating NATPmPPuncher.

Jan 14, 2015 21:31:36:712 [4320] DEBUG - NAT: Initializing NAT-PMP, success=1

Jan 14, 2015 21:31:36:712 [4320] DEBUG - NAT: Finding public address failed the first way, switching.

Jan 14, 2015 21:31:36:712 [4320] DEBUG - NAT: Switching to UPnP strategy.

Jan 14, 2015 21:31:36:712 [4320] DEBUG - NAT: Creating NATUPnPPuncher.

Jan 14, 2015 21:31:36:714 [6088] DEBUG - NAT: We’re going to next poll in 22.0 hours.

Jan 14, 2015 21:31:40:160 [4320] DEBUG - NAT: Initialized UPnP, result: SUCCESS

Jan 14, 2015 21:31:40:369 [4320] DEBUG - NAT: Requested public address via UPnP (success=1)

Jan 14, 2015 21:31:40:369 [4320] DEBUG - NAT: Obtained public address of 90.200.72.35 (failed: 0 myPlex: 0).

Jan 14, 2015 21:31:40:369 [4320] DEBUG - NAT: Checking for change in address: old was 90.200.72.35 and new is 90.200.72.35.

Jan 14, 2015 21:31:40:369 [4320] DEBUG - NAT: Mapping unchanged.

Jan 14, 2015 21:31:40:376 [4320] DEBUG - MyPlex: Last published value didn’t change, we’re done.

Jan 14, 2015 21:31:40:376 [4320] DEBUG - NAT: Updating mapping complete in thread 03CFE660.

Jan 14, 2015 21:31:40:376 [4320] DEBUG - NAT: Deleting puncher.

Jan 14, 2015 21:31:40:376 [4320] DEBUG - NAT: Deleting NATUPnPPuncher.

Jan 14, 2015 21:31:40:376 [4320] DEBUG - NAT: Deleting NATPmPPuncher.

Jan 14, 2015 21:31:40:376 [4320] DEBUG - NetworkServices: Initializing…

Jan 14, 2015 21:31:40:376 [4320] DEBUG - 0515C288: Creating new Network Service and registering for notifications.

Jan 14, 2015 21:31:40:376 [4320] DEBUG - 0515C288: NetworkService got notification of changed network (first change: 1)

Jan 14, 2015 21:31:40:376 [4320] DEBUG - NetworkService: Quick dispatch of network change.

Jan 14, 2015 21:31:40:376 [4320] DEBUG - 05125568: Creating new Network Service and registering for notifications.

Jan 14, 2015 21:31:40:377 [4320] DEBUG - 05125568: NetworkService got notification of changed network (first change: 1)

Jan 14, 2015 21:31:40:377 [4320] DEBUG - NetworkService: Quick dispatch of network change.

Jan 14, 2015 21:31:40:377 [4320] DEBUG - 051529A8: Creating new Network Service and registering for notifications.

Jan 14, 2015 21:31:40:377 [4320] DEBUG - 051529A8: NetworkService got notification of changed network (first change: 1)

Jan 14, 2015 21:31:40:377 [4320] DEBUG - NetworkService: Quick dispatch of network change.

Jan 14, 2015 21:31:40:377 [4320] DEBUG - 051751B8: Creating new Network Service and registering for notifications.

Jan 14, 2015 21:31:40:377 [4320] DEBUG - 051751B8: NetworkService got notification of changed network (first change: 1)

Jan 14, 2015 21:31:40:377 [4320] DEBUG - NetworkService: Quick dispatch of network change.

Jan 14, 2015 21:31:40:377 [4320] DEBUG - Starting HTTP server.

Jan 14, 2015 21:31:40:377 [4320] DEBUG - Running server…

Jan 14, 2015 21:31:40:377 [4320] DEBUG - Waiting for server condition to be signaled.

Jan 14, 2015 21:31:40:378 [4580] DEBUG - DLNA server starting.

Jan 14, 2015 21:31:40:378 [4580] DEBUG - Job running: C:\Program Files\Plex\Plex Media Server\PlexDlnaServer.exe

Jan 14, 2015 21:31:40:378 [5788] DEBUG - Network change for advertiser.

Jan 14, 2015 21:31:40:378 [5788] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)

Jan 14, 2015 21:31:40:379 [5788] DEBUG - Network change for advertiser.

Jan 14, 2015 21:31:40:379 [5788] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)

Jan 14, 2015 21:31:40:379 [5788] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.

Jan 14, 2015 21:31:40:379 [5788] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)

Jan 14, 2015 21:31:40:380 [5788] DEBUG - NetworkService: Browsing on interface 127.0.0.1 on broadcast address 127.255.255.255 (index: 0)

Jan 14, 2015 21:31:40:380 [5788] DEBUG - NetworkService: Browsing on interface 192.168.0.13 on broadcast address 192.168.0.255 (index: 1)

Jan 14, 2015 21:31:40:380 [5788] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.

Jan 14, 2015 21:31:40:380 [5788] DEBUG - NetworkService: Browsing on interface 127.0.0.1 on broadcast address 127.255.255.255 (index: 0)

Jan 14, 2015 21:31:40:380 [5788] DEBUG - NetworkService: Browsing on interface 192.168.0.13 on broadcast address 192.168.0.255 (index: 1)

Jan 14, 2015 21:31:40:412 [5788] DEBUG - NetworkServiceBrowser: SERVER arrived: 127.0.0.1 (Media-PC)

Jan 14, 2015 21:31:40:412 [5788] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=127.0.0.1

Jan 14, 2015 21:31:40:412 [5788] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=127.0.0.1

Jan 14, 2015 21:31:40:413 [5788] DEBUG - HTTP requesting to: http://127.0.0.1:55148/system/notify/serverUpdated?host=127.0.0.1

Jan 14, 2015 21:31:40:416 [5620] DEBUG - EventSource: Resolved to 194.109.153.222

Jan 14, 2015 21:31:40:420 [5748] DEBUG - Set up a thread pool for HTTP server with 2 threads.

Jan 14, 2015 21:31:40:420 [5748] DEBUG - Running database fix-ups.

Jan 14, 2015 21:31:40:422 [5748] DEBUG - Database Fixups: Destroyed 0 transient items.

Jan 14, 2015 21:31:40:443 [5748] DEBUG - Running database fix-ups completed in 0.0 seconds.

Jan 14, 2015 21:31:40:450 [3568] DEBUG - Request: [127.0.0.1:55144] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (6 live)

Jan 14, 2015 21:31:40:451 [3568] DEBUG - Translator: Found locale ar

Jan 14, 2015 21:31:40:451 [3568] DEBUG - Translator: Found locale cs_CZ

Jan 14, 2015 21:31:40:451 [3568] DEBUG - Translator: Found locale da

Jan 14, 2015 21:31:40:451 [3568] DEBUG - Translator: Found locale de

Jan 14, 2015 21:31:40:451 [3568] DEBUG - Translator: Found locale en

Jan 14, 2015 21:31:40:451 [3568] DEBUG - Translator: Found locale es

Jan 14, 2015 21:31:40:451 [3568] DEBUG - Translator: Found locale fr

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale he

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale hr_HR

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale it

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale lt

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale nl

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale nl_BE

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale no

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale pt_BR

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale sr

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale sv

Jan 14, 2015 21:31:40:452 [3568] DEBUG - Translator: Found locale zh_CN

Jan 14, 2015 21:31:40:466 [3568] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_

Jan 14, 2015 21:31:40:467 [3568] DEBUG - HTTP requesting to: http://127.0.0.1:55148/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_

Jan 14, 2015 21:31:40:468 [3876] DEBUG - Request: [127.0.0.1:55145] GET / (6 live)

Jan 14, 2015 21:31:40:469 [3876] DEBUG - It took 0.000000 sec to serialize a list with 15 elements.

Jan 14, 2015 21:31:40:473 [5676] VERBOSE - Finished writing response for GET /, 0 bytes in 63ms

Jan 14, 2015 21:31:40:474 [3876] VERBOSE - Completed request: GET /: 2200

Jan 14, 2015 21:31:40:474 [4808] DEBUG - Request: [127.0.0.1:55146] GET / (6 live)

Jan 14, 2015 21:31:40:484 [4808] DEBUG - It took 0.000000 sec to serialize a list with 15 elements.

Jan 14, 2015 21:31:40:485 [4808] VERBOSE - Completed request: GET /: 2200

Jan 14, 2015 21:31:40:485 [5620] VERBOSE - Finished writing response for GET /, 0 bytes in 78ms

Jan 14, 2015 21:31:40:485 [4876] DEBUG - Request: [127.0.0.1:55147] GET /servers (6 live)

Jan 14, 2015 21:31:40:486 [4876] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jan 14, 2015 21:31:40:486 [4876] VERBOSE - Completed request: GET /servers: 2200

Jan 14, 2015 21:31:40:486 [5620] VERBOSE - Finished writing response for GET /servers, 0 bytes in 78ms

Jan 14, 2015 21:31:40:494 [3876] DEBUG - Request: [127.0.0.1:55174] GET / (6 live)

Jan 14, 2015 21:31:40:496 [3876] DEBUG - It took 0.000000 sec to serialize a list with 15 elements.

Jan 14, 2015 21:31:40:496 [5676] VERBOSE - Finished writing response for GET /, 0 bytes in 0ms

Jan 14, 2015 21:31:40:496 [3876] VERBOSE - Completed request: GET /: 2200

Jan 14, 2015 21:31:40:500 [3876] DEBUG - Request: [192.168.0.13:55175] GET /library/sections (6 live)

Jan 14, 2015 21:31:40:503 [3876] DEBUG - It took 0.001000 sec to serialize a list with 3 elements.

Jan 14, 2015 21:31:40:504 [3876] VERBOSE - Completed request: GET /library/sections: 2200

Jan 14, 2015 21:31:40:504 [5620] VERBOSE - Finished writing response for GET /library/sections, 0 bytes in 16ms

Jan 14, 2015 21:31:40:507 [5620] DEBUG - EventSource: Connected.

Jan 14, 2015 21:31:40:507 [5676] DEBUG - EventSource: Wrote data, reading reply.

Jan 14, 2015 21:31:40:537 [3876] DEBUG - Request: [192.168.0.13:55177] GET /channels/all (6 live)

Jan 14, 2015 21:31:40:537 [3876] DEBUG - It took 0.000000 sec to serialize a list with 2 elements.

Jan 14, 2015 21:31:40:538 [3876] VERBOSE - Completed request: GET /channels/all: 2200

Jan 14, 2015 21:31:40:538 [5620] VERBOSE - Finished writing response for GET /channels/all, 0 bytes in 0ms

Jan 14, 2015 21:31:40:669 [3568] DEBUG - [com.plexapp.system] HTTP reply status 200, with 84970 bytes of content.

Jan 14, 2015 21:31:40:675 [3568] VERBOSE - Completed request: GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_: 2200

Jan 14, 2015 21:31:40:675 [5620] VERBOSE - Finished writing response for GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_, 0 bytes in 265ms

Jan 14, 2015 21:31:40:744 [3876] DEBUG - Request: [127.0.0.1:55180] GET /servers (6 live)

Jan 14, 2015 21:31:40:744 [3876] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jan 14, 2015 21:31:40:745 [5676] VERBOSE - Finished writing response for GET /servers, 0 bytes in 0ms

Jan 14, 2015 21:31:40:745 [3876] VERBOSE - Completed request: GET /servers: 2200

Jan 14, 2015 21:31:40:753 [3568] DEBUG - Request: [192.168.0.13:55181] GET /library/sections (6 live)

Jan 14, 2015 21:31:40:755 [3568] DEBUG - It took 0.000000 sec to serialize a list with 3 elements.

Jan 14, 2015 21:31:40:755 [3568] VERBOSE - Completed request: GET /library/sections: 2200

Jan 14, 2015 21:31:40:755 [5620] VERBOSE - Finished writing response for GET /library/sections, 0 bytes in 0ms

Jan 14, 2015 21:31:40:776 [4876] DEBUG - Request: [192.168.0.13:55182] GET /channels/all (6 live)

Jan 14, 2015 21:31:40:777 [4876] DEBUG - It took 0.000000 sec to serialize a list with 2 elements.

Jan 14, 2015 21:31:40:778 [5620] VERBOSE - Finished writing response for GET /channels/all, 0 bytes in 16ms

Jan 14, 2015 21:31:40:778 [4876] VERBOSE - Completed request: GET /channels/all: 2200

Jan 14, 2015 21:31:40:783 [5788] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.

Jan 14, 2015 21:31:41:426 [5620] DEBUG - EventSource: Read HTTP reply header.

Jan 14, 2015 21:31:42:394 [4808] DEBUG - Request: [127.0.0.1:55235] GET /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 (6 live)

Jan 14, 2015 21:31:42:394 [4808] DEBUG -  * format => png

Jan 14, 2015 21:31:42:394 [4808] DEBUG -  * height => 260

Jan 14, 2015 21:31:42:394 [4808] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:42:394 [4808] DEBUG -  * width => 260

Jan 14, 2015 21:31:42:395 [4808] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:42:395 [4808] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260

Jan 14, 2015 21:31:42:395 [4808] VERBOSE - [IDLE] Server is now active

Jan 14, 2015 21:31:42:395 [4808] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:396 [4808] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\e9\e9de10bb260bcedaec26d1c65705036ba4796df9.png is 71936.

Jan 14, 2015 21:31:42:397 [4808] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260: 2200

Jan 14, 2015 21:31:42:441 [5620] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260, 71936 bytes in 47ms

Jan 14, 2015 21:31:42:505 [4876] DEBUG - Request: [127.0.0.1:55240] GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 (6 live)

Jan 14, 2015 21:31:42:505 [4876] DEBUG -  * format => png

Jan 14, 2015 21:31:42:505 [4876] DEBUG -  * height => 120

Jan 14, 2015 21:31:42:505 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:42:505 [4876] DEBUG -  * width => 120

Jan 14, 2015 21:31:42:506 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:42:506 [4876] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:42:506 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 1 active item(s)

Jan 14, 2015 21:31:42:506 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:507 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\74\7446e002576273430ce9b37dfc5a1e20e2157e59.png is 20177.

Jan 14, 2015 21:31:42:508 [4876] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120: 2200

Jan 14, 2015 21:31:42:508 [5620] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120, 20177 bytes in 0ms

Jan 14, 2015 21:31:42:539 [4876] VERBOSE - [IDLE] Removing (1->0) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:42:539 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:539 [4876] DEBUG - Request: [127.0.0.1:55252] GET /photo/:/transcode?format=png&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=48 (6 live)

Jan 14, 2015 21:31:42:540 [4876] DEBUG -  * format => png

Jan 14, 2015 21:31:42:540 [4876] DEBUG -  * height => 48

Jan 14, 2015 21:31:42:540 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:42:540 [4876] DEBUG -  * width => 48

Jan 14, 2015 21:31:42:540 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:42:541 [4876] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?format=png&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=48

Jan 14, 2015 21:31:42:541 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:541 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=48 - 1 active item(s)

Jan 14, 2015 21:31:42:541 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\b6\b6e471dc3b56bfd4e33c5b74dcebd411f2da16a7.png is 4682.

Jan 14, 2015 21:31:42:542 [4876] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=48: 2200

Jan 14, 2015 21:31:42:542 [5620] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=48, 4682 bytes in 0ms

Jan 14, 2015 21:31:42:562 [4876] VERBOSE - [IDLE] Removing (1->0) work item http_download - /photo/:/transcode?format=png&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=48

Jan 14, 2015 21:31:42:562 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:562 [4876] DEBUG - Request: [127.0.0.1:55255] GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=260 (6 live)

Jan 14, 2015 21:31:42:562 [4876] DEBUG -  * chromaSubsampling => 4:2:2

Jan 14, 2015 21:31:42:562 [4876] DEBUG -  * format => jpg

Jan 14, 2015 21:31:42:562 [4876] DEBUG -  * height => 260

Jan 14, 2015 21:31:42:562 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.jpg

Jan 14, 2015 21:31:42:562 [4876] DEBUG -  * width => 260

Jan 14, 2015 21:31:42:563 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.jpg] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:42:563 [4876] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=260

Jan 14, 2015 21:31:42:563 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:564 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:564 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\fa\fab9b467e1704a6cd1e9345ff2e7d2eec12c3a83.jpg is 7889.

Jan 14, 2015 21:31:42:565 [4876] VERBOSE - Completed request: GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=260: 2200

Jan 14, 2015 21:31:42:565 [5620] VERBOSE - Finished writing response for GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=260, 7889 bytes in 0ms

Jan 14, 2015 21:31:42:589 [4876] VERBOSE - [IDLE] Removing (1->0) work item http_download - /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=260

Jan 14, 2015 21:31:42:589 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:589 [4876] DEBUG - Request: [127.0.0.1:55261] GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=120 (6 live)

Jan 14, 2015 21:31:42:589 [4876] DEBUG -  * chromaSubsampling => 4:2:2

Jan 14, 2015 21:31:42:589 [4876] DEBUG -  * format => jpg

Jan 14, 2015 21:31:42:589 [4876] DEBUG -  * height => 120

Jan 14, 2015 21:31:42:590 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.jpg

Jan 14, 2015 21:31:42:590 [4876] DEBUG -  * width => 120

Jan 14, 2015 21:31:42:590 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.jpg] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:42:591 [4876] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=120

Jan 14, 2015 21:31:42:591 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=120 - 1 active item(s)

Jan 14, 2015 21:31:42:591 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:591 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\f6\f6daeee401de3199880a038a3420a3c0e3b0af1b.jpg is 2706.

Jan 14, 2015 21:31:42:592 [4876] VERBOSE - Completed request: GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=120: 2200

Jan 14, 2015 21:31:42:592 [5620] VERBOSE - Finished writing response for GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=120, 2706 bytes in 15ms

Jan 14, 2015 21:31:42:607 [4876] VERBOSE - [IDLE] Removing (1->0) work item http_download - /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=120

Jan 14, 2015 21:31:42:608 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:608 [4876] DEBUG - Request: [127.0.0.1:55267] GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=48 (6 live)

Jan 14, 2015 21:31:42:608 [4876] DEBUG -  * chromaSubsampling => 4:2:2

Jan 14, 2015 21:31:42:608 [4876] DEBUG -  * format => jpg

Jan 14, 2015 21:31:42:608 [4876] DEBUG -  * height => 48

Jan 14, 2015 21:31:42:608 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.jpg

Jan 14, 2015 21:31:42:608 [4876] DEBUG -  * width => 48

Jan 14, 2015 21:31:42:608 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.jpg] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:42:609 [4876] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=48

Jan 14, 2015 21:31:42:609 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=48 - 1 active item(s)

Jan 14, 2015 21:31:42:609 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:610 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\bb\bb65c1ca4df0b4281f5f8086875e71ff120b0120.jpg is 949.

Jan 14, 2015 21:31:42:610 [4876] VERBOSE - Completed request: GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=48: 2200

Jan 14, 2015 21:31:42:611 [5620] VERBOSE - Finished writing response for GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=48, 949 bytes in 0ms

Jan 14, 2015 21:31:42:907 [4876] VERBOSE - [IDLE] Removing (1->0) work item http_download - /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=48

Jan 14, 2015 21:31:42:907 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:907 [4876] DEBUG - Request: [127.0.0.1:55287] GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 (6 live)

Jan 14, 2015 21:31:42:907 [4876] DEBUG -  * format => png

Jan 14, 2015 21:31:42:907 [4876] DEBUG -  * height => 120

Jan 14, 2015 21:31:42:907 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:42:907 [4876] DEBUG -  * width => 120

Jan 14, 2015 21:31:42:908 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:42:908 [4876] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:42:908 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 1 active item(s)

Jan 14, 2015 21:31:42:909 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:909 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\74\7446e002576273430ce9b37dfc5a1e20e2157e59.png is 20177.

Jan 14, 2015 21:31:42:910 [4876] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120: 2200

Jan 14, 2015 21:31:42:910 [5676] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120, 20177 bytes in 0ms

Jan 14, 2015 21:31:42:978 [4876] VERBOSE - [IDLE] Removing (1->0) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:42:978 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:978 [4876] DEBUG - Request: [127.0.0.1:55294] GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 (6 live)

Jan 14, 2015 21:31:42:978 [4876] DEBUG -  * format => png

Jan 14, 2015 21:31:42:978 [4876] DEBUG -  * height => 120

Jan 14, 2015 21:31:42:978 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:42:978 [4876] DEBUG -  * width => 120

Jan 14, 2015 21:31:42:979 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:42:979 [4876] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:42:979 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 1 active item(s)

Jan 14, 2015 21:31:42:980 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:42:980 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\74\7446e002576273430ce9b37dfc5a1e20e2157e59.png is 20177.

Jan 14, 2015 21:31:42:980 [4876] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120: 2200

Jan 14, 2015 21:31:42:981 [5620] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120, 20177 bytes in 15ms

Jan 14, 2015 21:31:43:044 [4876] VERBOSE - [IDLE] Removing (1->0) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:43:044 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:43:044 [4876] DEBUG - Request: [127.0.0.1:55299] GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 (6 live)

Jan 14, 2015 21:31:43:044 [4876] DEBUG -  * format => png

Jan 14, 2015 21:31:43:044 [4876] DEBUG -  * height => 120

Jan 14, 2015 21:31:43:044 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:43:045 [4876] DEBUG -  * width => 120

Jan 14, 2015 21:31:43:045 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:43:046 [4876] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:43:046 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 1 active item(s)

Jan 14, 2015 21:31:43:046 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:43:046 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\74\7446e002576273430ce9b37dfc5a1e20e2157e59.png is 20177.

Jan 14, 2015 21:31:43:047 [4876] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120: 2200

Jan 14, 2015 21:31:43:047 [5620] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120, 20177 bytes in 16ms

Jan 14, 2015 21:31:43:108 [4876] VERBOSE - [IDLE] Removing (1->0) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:43:108 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:43:108 [4876] DEBUG - Request: [127.0.0.1:55304] GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 (6 live)

Jan 14, 2015 21:31:43:108 [4876] DEBUG -  * format => png

Jan 14, 2015 21:31:43:108 [4876] DEBUG -  * height => 120

Jan 14, 2015 21:31:43:108 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:43:108 [4876] DEBUG -  * width => 120

Jan 14, 2015 21:31:43:109 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:43:109 [4876] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:43:109 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 1 active item(s)

Jan 14, 2015 21:31:43:109 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:43:109 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\74\7446e002576273430ce9b37dfc5a1e20e2157e59.png is 20177.

Jan 14, 2015 21:31:43:110 [4876] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120: 2200

Jan 14, 2015 21:31:43:110 [5620] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120, 20177 bytes in 0ms

Jan 14, 2015 21:31:43:180 [4876] VERBOSE - [IDLE] Removing (1->0) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:43:180 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:43:180 [4876] DEBUG - Request: [127.0.0.1:55309] GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 (6 live)

Jan 14, 2015 21:31:43:180 [4876] DEBUG -  * format => png

Jan 14, 2015 21:31:43:180 [4876] DEBUG -  * height => 120

Jan 14, 2015 21:31:43:180 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:43:180 [4876] DEBUG -  * width => 120

Jan 14, 2015 21:31:43:181 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:43:181 [4876] VERBOSE - [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:43:181 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 1 active item(s)

Jan 14, 2015 21:31:43:181 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:43:181 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\74\7446e002576273430ce9b37dfc5a1e20e2157e59.png is 20177.

Jan 14, 2015 21:31:43:182 [4876] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120: 2200

Jan 14, 2015 21:31:43:182 [5676] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120, 20177 bytes in 0ms

Jan 14, 2015 21:31:43:243 [3568] DEBUG - Request: [127.0.0.1:55316] GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 (6 live)

Jan 14, 2015 21:31:43:243 [3568] DEBUG -  * format => png

Jan 14, 2015 21:31:43:243 [3568] DEBUG -  * height => 120

Jan 14, 2015 21:31:43:243 [3568] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:43:243 [3568] DEBUG -  * width => 120

Jan 14, 2015 21:31:43:244 [3568] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:43:244 [3568] VERBOSE - [IDLE] Adding (1->2) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:43:244 [3568] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 2 active item(s)

Jan 14, 2015 21:31:43:244 [3568] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:43:244 [3568] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\74\7446e002576273430ce9b37dfc5a1e20e2157e59.png is 20177.

Jan 14, 2015 21:31:43:245 [3568] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120: 2200

Jan 14, 2015 21:31:43:245 [5620] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120, 20177 bytes in 0ms

Jan 14, 2015 21:31:43:443 [3484] DEBUG - Device: Microsoft PC

Jan 14, 2015 21:31:44:125 [4876] VERBOSE - [IDLE] Removing (2->1) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:44:125 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 1 active item(s)

Jan 14, 2015 21:31:44:125 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:44:125 [4876] DEBUG - Request: [127.0.0.1:55332] GET /library/sections (6 live)

Jan 14, 2015 21:31:44:127 [4876] DEBUG - It took 0.000000 sec to serialize a list with 3 elements.

Jan 14, 2015 21:31:44:128 [4876] VERBOSE - Completed request: GET /library/sections: 2200

Jan 14, 2015 21:31:44:128 [5620] VERBOSE - Finished writing response for GET /library/sections, 0 bytes in 16ms

Jan 14, 2015 21:31:44:798 [4876] DEBUG - Request: [127.0.0.1:55348] GET /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 (6 live)

Jan 14, 2015 21:31:44:798 [4876] DEBUG -  * format => png

Jan 14, 2015 21:31:44:798 [4876] DEBUG -  * height => 260

Jan 14, 2015 21:31:44:798 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:44:798 [4876] DEBUG -  * width => 260

Jan 14, 2015 21:31:44:799 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:44:799 [4876] VERBOSE - [IDLE] Adding (1->2) work item http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260

Jan 14, 2015 21:31:44:800 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 1 active item(s)

Jan 14, 2015 21:31:44:800 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 2 active item(s)

Jan 14, 2015 21:31:44:800 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\e9\e9de10bb260bcedaec26d1c65705036ba4796df9.png is 71936.

Jan 14, 2015 21:31:44:801 [4876] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260: 2200

Jan 14, 2015 21:31:44:839 [5676] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260, 71936 bytes in 63ms

Jan 14, 2015 21:31:44:941 [4876] VERBOSE - [IDLE] Removing (2->1) work item http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260

Jan 14, 2015 21:31:44:941 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 1 active item(s)

Jan 14, 2015 21:31:44:941 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:44:941 [4876] DEBUG - Request: [127.0.0.1:55353] GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 (6 live)

Jan 14, 2015 21:31:44:941 [4876] DEBUG -  * format => png

Jan 14, 2015 21:31:44:941 [4876] DEBUG -  * height => 120

Jan 14, 2015 21:31:44:942 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:44:942 [4876] DEBUG -  * width => 120

Jan 14, 2015 21:31:44:942 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:44:943 [4876] VERBOSE - [IDLE] Adding (1->2) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:44:943 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 2 active item(s)

Jan 14, 2015 21:31:44:943 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:44:943 [4876] DEBUG - Content-Length of C:\Users\DELL\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\74\7446e002576273430ce9b37dfc5a1e20e2157e59.png is 20177.

Jan 14, 2015 21:31:44:944 [4876] VERBOSE - Completed request: GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120: 2200

Jan 14, 2015 21:31:44:944 [5676] VERBOSE - Finished writing response for GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120, 20177 bytes in 0ms

Jan 14, 2015 21:31:44:970 [4876] VERBOSE - [IDLE] Removing (2->1) work item http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120

Jan 14, 2015 21:31:44:970 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 - 1 active item(s)

Jan 14, 2015 21:31:44:970 [4876] VERBOSE - [IDLE] * http_download - /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 - 1 active item(s)

Jan 14, 2015 21:31:44:970 [4876] DEBUG - Request: [127.0.0.1:55356] GET /photo/:/transcode?format=png&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=48 (6 live)

Jan 14, 2015 21:31:44:970 [4876] DEBUG -  * format => png

Jan 14, 2015 21:31:44:970 [4876] DEBUG -  * height => 48

Jan 14, 2015 21:31:44:971 [4876] DEBUG -  * url => http://127.0.0.1:32400/:/resources/dlna-icon-260.png

Jan 14, 2015 21:31:44:971 [4876] DEBUG -  * width => 48

Jan 14, 2015 21:31:44:971 [4876] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)

Jan 14, 2015 21:31:44:972 [4876] VERBOSE - [IDLE] Adding (0

vCJv · January 14, 2015, 9:51pm

Double post.

vCJv · January 16, 2015, 11:34pm

Can someone just confirm my CPU/drive/network speed and spikes are expected when streaming a 7GB MKV to ATV?

MKV video is direct, audio is transcoded:

M4V file:

My transcoded settings, how can I improve these to smooth out the delivery, as my CPU doesn’t appear pushed?

pecinko · January 17, 2015, 3:23pm

Set transcoding in PlexConnect settings to 40. Any better? For best results, your MKV files should have sane amount of ref frames and reasonable bitrate.

vCJv · January 17, 2015, 3:28pm

Set transcoding in PlexConnect settings to 40. Any better? For best results, your MKV files should have sane amount of ref frames and reasonable bitrate.

Hi, which transcoder setting to 40? On the ATV? These are already 40Mbps.

Topic		Replies	Views
Buffering issues with plex connect/ pms Apps & Tools plexconnect	20	268	December 21, 2019
1080p works at 20Mbps, breaks at 40Mbps Apps & Tools plexconnect	17	495	December 20, 2019
Transcoding Issues Apps & Tools plexconnect	47	446	December 20, 2019
Workaround for stutter lag issues over WiFi Apps & Tools plexconnect	23	1140	December 21, 2019
Watching first movies and disappointed Desktop Players server-mac , apple-tv	15	462	September 23, 2021

MKV movies stutter/buffer after ~40mins | Speed = 10+

Related topics