May 07, 2019 07:14:42.869 [0x14d0ced59700] INFO - Plex Media Server v1.15.5.994-4610c6e8d - Docker Docker Container (LinuxServer.io) x86_64 - build: linux-x86_64 debian - GMT -05:00
May 07, 2019 07:14:42.869 [0x14d0ced59700] INFO - Linux version: 4.19.37-Unraid, language: en-US
May 07, 2019 07:14:42.869 [0x14d0ced59700] INFO - Processor Intel(R) Core(TM) i7-4770 CPU @ 3.40GHz
May 07, 2019 07:14:42.869 [0x14d0ced59700] INFO - /usr/lib/plexmediaserver/Plex Media Server
May 07, 2019 07:14:42.870 [0x14d0db4a4f00] INFO - SQLITE3:(nil), 283, recovered 331 frames from WAL file /config/Library/Application Support/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.db-wal
May 07, 2019 07:14:42.960 [0x14d0db4a4f00] INFO - OCSP: Successfully retrieved response from cache.
May 07, 2019 07:14:44.348 [0x14d08ebf5700] WARN - Held transaction for too long (../Library/MetadataCollection.cpp:236): 0.130000 seconds
May 07, 2019 07:14:45.086 [0x14d08e5f2700] INFO - AutoUpdate: no updates available
May 07, 2019 07:14:46.489 [0x14d08fbfd700] INFO - Sync: downloaded 0 sync list(s) with 0 sync items(s): 0 new, 0 updated, 0 deleted
May 07, 2019 07:14:46.489 [0x14d08d4e9700] WARN - Sync: local sync directory "/config/Library/Application Support/Plex Media Server/Cache/Transcode/Sync+" does not exist
May 07, 2019 07:14:47.743 [0x14d08cae4700] ERROR - Error issuing curl_easy_perform(handle): 7
May 07, 2019 07:14:47.743 [0x14d08c8e3700] ERROR - Error issuing curl_easy_perform(handle): 7
May 07, 2019 07:14:47.743 [0x14d08cae4700] WARN - HTTP error requesting GET https://192-168-2-34.588c680b015043af895152d8d361f01e.plex.direct:32400 (0, No error) (Failed to connect to 192-168-2-34.588c680b015043af895152d8d361f01e.plex.direct port 32400: Network is unreachable)
May 07, 2019 07:14:47.744 [0x14d08c8e3700] WARN - HTTP error requesting GET https://192-168-1-149.4079ecb7b4b44f4d93ecf8c64b8c0d43.plex.direct:32400 (0, No error) (Failed to connect to 192-168-1-149.4079ecb7b4b44f4d93ecf8c64b8c0d43.plex.direct port 32400: Network is unreachable)
May 07, 2019 07:14:47.747 [0x14d08c6e2700] ERROR - Error issuing curl_easy_perform(handle): 51
May 07, 2019 07:14:47.748 [0x14d08c6e2700] WARN - HTTP error requesting GET https://172-17-0-1.4079ecb7b4b44f4d93ecf8c64b8c0d43.plex.direct:32400 (0, No error) (SSL: no alternative certificate subject name matches target host name '172-17-0-1.4079ecb7b4b44f4d93ecf8c64b8c0d43.plex.direct')
May 07, 2019 07:14:48.135 [0x14d08cee6700] ERROR - Unknown metadata type: 
May 07, 2019 07:14:48.470 [0x14d08c8e3700] ERROR - Error issuing curl_easy_perform(handle): 7
May 07, 2019 07:14:48.470 [0x14d08c2e0700] ERROR - Error issuing curl_easy_perform(handle): 7
May 07, 2019 07:14:48.470 [0x14d08c8e3700] WARN - HTTP error requesting GET https://192-168-3-8.6a6024fa20ac4916a61b5d46531a96d2.plex.direct:32400 (0, No error) (Failed to connect to 192-168-3-8.6a6024fa20ac4916a61b5d46531a96d2.plex.direct port 32400: Network is unreachable)
May 07, 2019 07:14:48.470 [0x14d08c2e0700] WARN - HTTP error requesting GET https://172-18-0-1.6a6024fa20ac4916a61b5d46531a96d2.plex.direct:32400 (0, No error) (Failed to connect to 172-18-0-1.6a6024fa20ac4916a61b5d46531a96d2.plex.direct port 32400: Network is unreachable)
May 07, 2019 07:14:48.603 [0x14d08c6e2700] ERROR - Error issuing curl_easy_perform(handle): 51
May 07, 2019 07:14:48.603 [0x14d08c6e2700] WARN - HTTP error requesting GET https://172-17-0-1.6a6024fa20ac4916a61b5d46531a96d2.plex.direct:32400 (0, No error) (SSL: no alternative certificate subject name matches target host name '172-17-0-1.6a6024fa20ac4916a61b5d46531a96d2.plex.direct')
May 07, 2019 07:14:48.757 [0x14d08cee6700] ERROR - Unknown metadata type: 
May 07, 2019 07:14:49.244 [0x14d08cee6700] ERROR - Unknown metadata type: 
May 07, 2019 07:14:49.248 [0x14d08e5f2700] ERROR - [PlexRelay] kex protocol error: type 7 seq 11
May 07, 2019 07:14:49.429 [0x14d08c8e3700] ERROR - Error issuing curl_easy_perform(handle): 51
May 07, 2019 07:14:49.429 [0x14d08c8e3700] WARN - HTTP error requesting GET https://172-17-0-1.4d38b37d6e5e4c87857b051544742f04.plex.direct:32400 (0, No error) (SSL: no alternative certificate subject name matches target host name '172-17-0-1.4d38b37d6e5e4c87857b051544742f04.plex.direct')
May 07, 2019 07:14:49.579 [0x14d08f9fc700] INFO - Refreshing paths watched by LibraryUpdateManager
May 07, 2019 07:14:49.836 [0x14d08e9f4700] INFO - [PlexRelay] Allocated port 13713 for remote forward to 127.0.0.1:32401
May 07, 2019 07:14:49.860 [0x14d08cee6700] ERROR - Unknown metadata type: 
May 07, 2019 07:14:52.617 [0x14d08c4e1700] ERROR - Error issuing curl_easy_perform(handle): 28
May 07, 2019 07:14:53.705 [0x14d0cca96700] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 07, 2019 07:14:54.293 [0x14d08c6e2700] ERROR - Error issuing curl_easy_perform(handle): 28
May 07, 2019 07:14:57.616 [0x14d08d2e8700] WARN - Connection Tester: Error testing media server Plexual_Healing in 10000 ms.
May 07, 2019 07:14:57.617 [0x14d0cce98700] ERROR - Error issuing curl_easy_perform(handle): 3
May 07, 2019 07:14:57.617 [0x14d0cce98700] WARN - HTTP error requesting GET /media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (<url> malformed)
May 07, 2019 07:14:57.937 [0x14d0cce98700] ERROR - Unknown metadata type: 
May 07, 2019 07:15:00.471 [0x14d0cce98700] ERROR - Unknown metadata type: 
May 07, 2019 07:15:01.635 [0x14d0cce98700] ERROR - Unknown metadata type: 
May 07, 2019 07:15:10.044 [0x14d08e7f3700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
May 07, 2019 07:15:14.526 [0x14d08e9f4700] INFO - AutoUpdate: no updates available
May 07, 2019 07:15:17.558 [0x14d08eff7700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 07:17:02.953 [0x14d08e5f2700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 07:18:26.949 [0x14d08e9f4700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 07:18:43.959 [0x14d08e9f4700] INFO - AutoUpdate: no updates available
May 07, 2019 07:18:53.659 [0x14d08e5f2700] INFO - AutoUpdate: no updates available
May 07, 2019 07:18:54.202 [0x14d08d2e8700] INFO - AutoUpdate: no updates available
May 07, 2019 07:19:03.301 [0x14d08cee6700] INFO - AutoUpdate: no updates available
May 07, 2019 07:19:17.012 [0x14d08eff7700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
May 07, 2019 07:20:35.960 [0x14d08d2e8700] ERROR - [PlexRelay] kex protocol error: type 7 seq 11
May 07, 2019 07:20:37.422 [0x14d08e7f3700] INFO - [PlexRelay] Allocated port 4879 for remote forward to 127.0.0.1:32401
May 07, 2019 07:22:22.728 [0x14d08e7f3700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 07:22:44.823 [0x14d08fbfd700] INFO - AutoUpdate: no updates available
May 07, 2019 07:22:57.451 [0x14d08cce5700] WARN - Transcode runner appears to have died.
May 07, 2019 07:44:58.687 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 600
May 07, 2019 08:00:47.123 [0x14d08c6e2700] INFO - AutoUpdate: no updates available
May 07, 2019 08:00:54.469 [0x14d08e9f4700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 08:04:17.757 [0x14d08e9f4700] INFO - AutoUpdate: no updates available
May 07, 2019 08:04:52.040 [0x14d08cce5700] INFO - AutoUpdate: no updates available
May 07, 2019 08:04:57.973 [0x14d08eff7700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 08:05:46.093 [0x14d08eff7700] INFO - AutoUpdate: no updates available
May 07, 2019 08:06:21.621 [0x14d08c8e3700] INFO - AutoUpdate: no updates available
May 07, 2019 08:06:26.699 [0x14d08cce5700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
May 07, 2019 08:09:21.245 [0x14d08d2e8700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 08:09:21.245 [0x14d08d2e8700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=c7cf1fb4-061c-4cc6-bfd9-dbe64fda5df4 (0, No error) (Empty reply from server)
May 07, 2019 08:11:19.102 [0x14d08e9f4700] ERROR - QueryParser: Invalid field 'sectionID' found, ignoring.
May 07, 2019 08:11:47.318 [0x14d08cce5700] ERROR - QueryParser: Invalid field 'sectionID' found, ignoring.
May 07, 2019 08:14:53.780 [0x14d08ffff700] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 07, 2019 08:32:25.872 [0x14d08eff7700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 08:32:47.837 [0x14d08e1f0700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 08:32:59.299 [0x14d08d7eb700] INFO - AutoUpdate: no updates available
May 07, 2019 08:33:02.580 [0x14d08e9f4700] INFO - AutoUpdate: no updates available
May 07, 2019 08:33:20.460 [0x14d08fdfe700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.160000 seconds
May 07, 2019 08:53:52.629 [0x14d08dfef700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 09:12:54.673 [0x14d0cc694700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 09:14:53.876 [0x14d08d4e9700] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 07, 2019 10:14:53.967 [0x14d0ce957700] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 07, 2019 10:18:40.057 [0x14d08d7eb700] ERROR - Unable to find client profile for device; platform=, platformVersion=, device=, model=
May 07, 2019 10:22:41.051 [0x14d08d2e8700] ERROR - QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
May 07, 2019 10:22:48.990 [0x14d0ce756700] ERROR - WebSocket: closing connection due to fragmentation
May 07, 2019 10:22:54.033 [0x14d08e9f4700] WARN - WebSocket: Ignoring message since we're closing the connection
May 07, 2019 10:22:54.034 [0x14d08c4e1700] WARN - WebSocket: Ignoring message since we're closing the connection
May 07, 2019 10:22:54.034 [0x14d08c4e1700] WARN - WebSocket: Ignoring message since we're closing the connection
May 07, 2019 10:22:54.034 [0x14d08c4e1700] WARN - WebSocket: Ignoring message since we're closing the connection
May 07, 2019 10:22:54.034 [0x14d08c4e1700] WARN - WebSocket: Ignoring message since we're closing the connection
May 07, 2019 10:49:04.895 [0x14d0ce354700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 10:50:12.623 [0x14d08c8e3700] WARN - SLOW QUERY: It took 162630.000000 ms to retrieve 1 items.
May 07, 2019 10:50:12.644 [0x14d08fbfd700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 11:13:08.518 [0x14d0ccc97700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 11:14:54.063 [0x14d0cd49b700] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 07, 2019 11:15:08.849 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 11:17:39.323 [0x14d0cc493700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 11:32:41.640 [0x14d08c2e0700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 11:33:11.782 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 11:42:31.635 [0x14d08e5f2700] ERROR - QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
May 07, 2019 11:42:43.185 [0x14d0cd099700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 11:42:45.587 [0x14d08c8e3700] INFO - CodecManager: starting EAE at "/tmp/pms-bbb8108d-6d88-4dfd-aaff-cdfbf004b1a3/EasyAudioEncoder"
May 07, 2019 11:49:14.296 [0x14d0cc895700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 11:51:44.864 [0x14d0ce354700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 11:52:15.018 [0x14d0cc895700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 11:53:15.245 [0x14d08c4e1700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 11:55:15.677 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 12:01:50.890 [0x14d08e7f3700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 12:03:17.164 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 12:03:17.173 [0x14d08e7f3700] WARN - Took too long (0.130000 seconds) to start a transaction on ../BackgroundProcessing/BackgroundProcessingQueue.cpp:41
May 07, 2019 12:03:17.173 [0x14d08e7f3700] WARN - Transaction that was running was started on thread 0x14d08cae4700 at ../Statistics/StatisticsManager.cpp:249
May 07, 2019 12:04:17.403 [0x14d0cc493700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 12:04:47.607 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 12:08:18.369 [0x14d0ccc97700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 12:08:35.962 [0x14d08c6e2700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:08:48.517 [0x14d0cd099700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.210000 seconds
May 07, 2019 12:08:48.530 [0x14d08e9f4700] ERROR - QueryParser: Invalid field 'sectionID' found, ignoring.
May 07, 2019 12:08:56.010 [0x14d08d2e8700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:09:16.057 [0x14d08cce5700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:09:36.104 [0x14d08d7eb700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:09:48.817 [0x14d08ffff700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 12:09:56.154 [0x14d08c6e2700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:10:16.206 [0x14d08cce5700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:10:19.046 [0x14d0ce354700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 12:10:36.252 [0x14d08cee6700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:10:49.253 [0x14d0ce153700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.170000 seconds
May 07, 2019 12:10:56.299 [0x14d08e5f2700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:11:16.345 [0x14d08cee6700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:11:36.392 [0x14d08e9f4700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:11:56.438 [0x14d08e9f4700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:12:16.485 [0x14d08c8e3700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:12:36.531 [0x14d08cee6700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:12:49.753 [0x14d08d4e9700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.150000 seconds
May 07, 2019 12:12:56.577 [0x14d08eff7700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:13:36.669 [0x14d08d2e8700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:13:50.002 [0x14d0ccc97700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 12:13:56.716 [0x14d08e9f4700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:14:16.763 [0x14d08e5f2700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:14:20.213 [0x14d08d4e9700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.200000 seconds
May 07, 2019 12:14:36.809 [0x14d08e5f2700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:14:50.335 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 12:14:54.156 [0x14d0cd49b700] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 07, 2019 12:14:56.855 [0x14d08d2e8700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:15:16.902 [0x14d08c6e2700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:15:20.515 [0x14d0cd29a700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.160000 seconds
May 07, 2019 12:15:36.948 [0x14d08eff7700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:15:50.700 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.150000 seconds
May 07, 2019 12:15:56.995 [0x14d08d2e8700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:16:17.046 [0x14d08e5f2700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:16:37.092 [0x14d08e9f4700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:16:51.098 [0x14d08d4e9700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.210000 seconds
May 07, 2019 12:16:57.141 [0x14d08c6e2700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:17:17.191 [0x14d08d7eb700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:17:21.319 [0x14d08c2e0700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 12:17:37.238 [0x14d08c8e3700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:17:57.284 [0x14d08e7f3700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:18:17.334 [0x14d08fbfd700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:18:37.386 [0x14d08e5f2700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:18:57.436 [0x14d08d7eb700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:19:17.489 [0x14d08e1f0700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:19:37.541 [0x14d08d7eb700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:19:57.591 [0x14d08e1f0700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:20:17.641 [0x14d08d2e8700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:20:37.695 [0x14d08e7f3700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:20:57.746 [0x14d08c8e3700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:21:17.796 [0x14d08eff7700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:21:37.847 [0x14d08d2e8700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:21:57.898 [0x14d08e5f2700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:22:17.944 [0x14d08e7f3700] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 07, 2019 12:22:52.676 [0x14d0cd49b700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.190000 seconds
May 07, 2019 12:23:22.810 [0x14d0cc292700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 12:25:23.356 [0x14d0ce153700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.160000 seconds
May 07, 2019 12:26:23.612 [0x14d0cd099700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 12:27:54.046 [0x14d0cd29a700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.180000 seconds
May 07, 2019 12:28:24.242 [0x14d08fdfe700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.160000 seconds
May 07, 2019 12:29:24.589 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.230000 seconds
May 07, 2019 12:29:54.827 [0x14d0cd099700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.220000 seconds
May 07, 2019 12:30:25.120 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.230000 seconds
May 07, 2019 12:31:25.401 [0x14d0cd099700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 12:32:55.806 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.150000 seconds
May 07, 2019 12:33:56.058 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 12:35:26.486 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 12:36:26.782 [0x14d0cd49b700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 12:37:27.133 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.190000 seconds
May 07, 2019 12:38:27.379 [0x14d0cca96700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 12:39:57.815 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.180000 seconds
May 07, 2019 12:41:28.201 [0x14d08ffff700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 12:42:28.435 [0x14d0cd29a700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 12:43:58.784 [0x14d0ccc97700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.170000 seconds
May 07, 2019 12:43:59.113 [0x14d08dfef700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 12:45:59.296 [0x14d08fdfe700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 12:46:29.551 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.220000 seconds
May 07, 2019 12:46:59.772 [0x14d0ccc97700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.180000 seconds
May 07, 2019 12:49:37.124 [0x14d08dfef700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 12:51:00.876 [0x14d0ccc97700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.200000 seconds
May 07, 2019 12:51:31.105 [0x14d0cc493700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.200000 seconds
May 07, 2019 12:52:01.335 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.200000 seconds
May 07, 2019 12:52:31.556 [0x14d08c2e0700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.200000 seconds
May 07, 2019 12:55:32.347 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 12:56:02.641 [0x14d0cc493700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.150000 seconds
May 07, 2019 12:56:32.803 [0x14d08ffff700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 12:59:33.637 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.190000 seconds
May 07, 2019 13:00:03.784 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 13:01:04.042 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 13:03:29.650 [0x14d027dfe700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 13:04:55.138 [0x14d027bfd700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 13:05:05.025 [0x14d0ce354700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.170000 seconds
May 07, 2019 13:05:18.088 [0x14d0277fb700] INFO - Library section 1 (TV Shows) will be updated because of a change in '"/media/Anime/TV/One-Punch Man/Season 2"/One-Punch Man - S02E05 - The Martial Arts Tournament HDTV-1080p.mkv.partial~'
May 07, 2019 13:05:35.153 [0x14d08c4e1700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 13:07:35.706 [0x14d08ffff700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.170000 seconds
May 07, 2019 13:08:35.942 [0x14d0cca96700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 13:09:08.059 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 13:09:14.247 [0x14d08d2e8700] WARN - Unable to load episode file ["seasons/2/episodes/5.xml"]
May 07, 2019 13:09:44.512 [0x14d08c8e3700] WARN - Unable to load episode file ["seasons/2/episodes/1.xml"]
May 07, 2019 13:09:44.825 [0x14d08cee6700] WARN - Unable to load episode file ["seasons/2/episodes/3.xml"]
May 07, 2019 13:09:51.777 [0x14d08fbfd700] WARN - Unable to load episode file ["seasons/2/episodes/2.xml"]
May 07, 2019 13:09:51.908 [0x14d08e7f3700] WARN - Unable to load episode file ["seasons/2/episodes/4.xml"]
May 07, 2019 13:13:05.749 [0x14d026ff7700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 13:14:09.266 [0x14d08c2e0700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.190000 seconds
May 07, 2019 13:14:34.067 [0x14d0ce756700] ERROR - Session 0x14d05004b230 terminated
May 07, 2019 13:14:34.211 [0x14d026ff7700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 13:14:54.548 [0x14d08c4e1700] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 07, 2019 13:15:09.576 [0x14d0ccc97700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.150000 seconds
May 07, 2019 13:21:41.044 [0x14d08c4e1700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.170000 seconds
May 07, 2019 13:28:42.625 [0x14d0cca96700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 13:36:44.428 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 13:37:14.597 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 13:39:41.412 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 7191
May 07, 2019 13:42:50.609 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 7297
May 07, 2019 13:42:50.796 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 7184
May 07, 2019 13:42:53.541 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 7340
May 07, 2019 13:44:35.114 [0x14d0277fb700] INFO - Library section 3 (Movies) will be updated because of a change in '"/media/Movies/What Men Want (2019)"/What Men Want (2019) [] - Bluray-1080p.mkv.backup~'
May 07, 2019 13:44:46.209 [0x14d0cc493700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 13:45:16.355 [0x14d08fdfe700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 13:45:35.462 [0x14d0277fb700] INFO - Library section 3 (Movies) will be updated because of a change in '"/media/Movies/What Men Want (2019)"/What Men Want (2019) [] - Bluray-1080p.mkv.backup~'
May 07, 2019 13:48:17.137 [0x14d0ccc97700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 13:49:17.380 [0x14d0cd49b700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 13:51:17.959 [0x14d08fdfe700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.180000 seconds
May 07, 2019 13:52:18.220 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 13:53:18.467 [0x14d0cc694700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 13:54:18.733 [0x14d0cc292700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 13:54:48.859 [0x14d08d4e9700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 13:56:07.874 [0x14d08fbfd700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 13:56:49.360 [0x14d0ce354700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 13:57:19.548 [0x14d0cd099700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.160000 seconds
May 07, 2019 13:57:24.613 [0x14d0277fb700] INFO - Library section 3 (Movies) will be updated because of a change in '"/media/Movies/Prodigy, The (2019)"/The Prodigy (2019) [] - Bluray-1080p.mkv.backup~'
May 07, 2019 13:57:45.469 [0x14d026ff7700] WARN - Error refreshing metadata for com.plexapp.agents.imdb://tt7634968?lang=en
May 07, 2019 13:57:49.688 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 13:58:16.263 [0x14d0277fb700] INFO - Library section 3 (Movies) will be updated because of a change in '"/media/Movies/Prodigy, The (2019)"/The Prodigy (2019) [] - Bluray-1080p.mkv.backup~'
May 07, 2019 13:58:19.864 [0x14d0cc292700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 13:58:50.082 [0x14d0cd49b700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.210000 seconds
May 07, 2019 13:59:20.211 [0x14d0cc292700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 13:59:50.375 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 14:00:20.756 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 14:00:50.892 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 14:01:15.897 [0x14d08e1f0700] WARN - MyPlex: we have an existing connectivity refresh request for async identifier fc43bae5-1616-4ea8-a61b-daf7afa3ad2d, but it has expired so we will try again.
May 07, 2019 14:02:51.372 [0x14d08ffff700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 14:03:21.568 [0x14d08c4e1700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.200000 seconds
May 07, 2019 14:04:21.237 [0x14d08cce5700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:04:21.237 [0x14d08cce5700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=e59dfca8-1b00-4f01-bec4-4eaf7cd51c4d (0, No error) (Empty reply from server)
May 07, 2019 14:04:21.814 [0x14d0ce354700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 14:04:51.938 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 14:05:21.241 [0x14d08e9f4700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:05:21.241 [0x14d08e9f4700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=b12ecd19-1a5b-40e5-bf9f-48406d723181 (0, No error) (Empty reply from server)
May 07, 2019 14:05:22.125 [0x14d08c4e1700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.190000 seconds
May 07, 2019 14:06:52.534 [0x14d0cc694700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.150000 seconds
May 07, 2019 14:07:22.667 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 14:07:52.785 [0x14d0cca96700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 14:08:18.380 [0x14d08d7eb700] ERROR - [Transcoder] [Parsed_inlineass_2 @ 0x11c5b00] fontselect: Using default font family: (Times New Roman, 700, 0) -> /usr/lib/plexmediaserver/Resources/Fonts/DejaVuSans-Bold.ttf, 0, DejaVuSans-Bold[Parsed_inlineass_2 @ 0x11c5b00] 
May 07, 2019 14:08:53.033 [0x14d08c2e0700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 14:09:23.153 [0x14d08c4e1700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 14:09:53.297 [0x14d0cc292700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 14:10:21.244 [0x14d08d2e8700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:10:21.244 [0x14d08d2e8700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=24e78f62-2eca-4f02-9def-7487d48ff112 (0, No error) (Empty reply from server)
May 07, 2019 14:10:23.514 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.190000 seconds
May 07, 2019 14:10:45.249 [0x14d0277fb700] INFO - Library section 3 (Movies) will be updated because of a change in '"/media/Movies/Prodigy, The (2019)"/The Prodigy (2019) [] - Bluray-720p.en.srt'
May 07, 2019 14:10:53.734 [0x14d08fdfe700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.200000 seconds
May 07, 2019 14:11:23.956 [0x14d08c2e0700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.180000 seconds
May 07, 2019 14:11:54.134 [0x14d0cc694700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 14:12:21.247 [0x14d08e5f2700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:12:21.247 [0x14d08e5f2700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=606aca6a-ff78-464c-a9b0-709395f6daa1 (0, No error) (Empty reply from server)
May 07, 2019 14:13:54.750 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.200000 seconds
May 07, 2019 14:14:57.344 [0x14d0ce354700] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 07, 2019 14:16:14.351 [0x14d08c2e0700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:16:14.351 [0x14d08c2e0700] WARN - HTTP error requesting GET https://plex.tv/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1 (0, No error) (Empty reply from server)
May 07, 2019 14:16:19.712 [0x14d08d4e9700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:16:19.712 [0x14d08d4e9700] WARN - HTTP error requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Empty reply from server)
May 07, 2019 14:16:19.714 [0x14d08d4e9700] ERROR - Error parsing content.
May 07, 2019 14:16:19.751 [0x14d08d4e9700] ERROR - Error parsing XML: Error parsing file.
May 07, 2019 14:16:21.242 [0x14d08fbfd700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:16:21.242 [0x14d08fbfd700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=ebb986d5-3fbf-4839-98a5-077e69c31093 (0, No error) (Empty reply from server)
May 07, 2019 14:16:25.501 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.190000 seconds
May 07, 2019 14:18:26.104 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.150000 seconds
May 07, 2019 14:19:56.525 [0x14d08c4e1700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 14:20:31.827 [0x14d08cce5700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 14:21:26.914 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 14:21:57.041 [0x14d0cd49b700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 14:23:21.303 [0x14d08e5f2700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:23:21.303 [0x14d08e5f2700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=6dc38dfb-d595-4a9f-a694-b4aea97de54b (0, No error) (Empty reply from server)
May 07, 2019 14:23:57.550 [0x14d08fdfe700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 14:24:57.821 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 14:25:10.008 [0x14d0275fa700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 14:26:15.899 [0x14d08e5f2700] WARN - MyPlex: we have an existing connectivity refresh request for async identifier 138bcfed-d4ca-4aef-877f-b00ffb17f4ee, but it has expired so we will try again.
May 07, 2019 14:27:21.255 [0x14d08d2e8700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:27:21.255 [0x14d08d2e8700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=01ea65da-0413-40de-8b76-32ff44266e5a (0, No error) (Empty reply from server)
May 07, 2019 14:27:22.151 [0x14d08fbfd700] WARN - Transcode runner appears to have died.
May 07, 2019 14:27:23.122 [0x14d08e9f4700] WARN - Transcode runner appears to have died.
May 07, 2019 14:27:23.991 [0x14d08c8e3700] WARN - Transcode runner appears to have died.
May 07, 2019 14:29:08.408 [0x14d0ce756700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:29:08.408 [0x14d0ce756700] WARN - HTTP error requesting GET https://plex.tv/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1&unknownToken=xxxxxxxxxxxxxxxxxxxx (0, No error) (Empty reply from server)
May 07, 2019 14:31:21.237 [0x14d08e5f2700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:31:21.237 [0x14d08e5f2700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=72860824-5f2b-441b-a6e7-03ea32e5c2a4 (0, No error) (Empty reply from server)
May 07, 2019 14:33:53.126 [0x14d027fff700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 14:34:30.930 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 14:35:01.173 [0x14d0cd099700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.220000 seconds
May 07, 2019 14:35:31.343 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.150000 seconds
May 07, 2019 14:37:15.898 [0x14d08d2e8700] WARN - MyPlex: we have an existing connectivity refresh request for async identifier d4d32114-dad9-46cf-981e-e5a3a5351899, but it has expired so we will try again.
May 07, 2019 14:37:21.271 [0x14d08d2e8700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:37:21.271 [0x14d08d2e8700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=7c352a1c-85e9-4563-9974-ef2823e63457 (0, No error) (Empty reply from server)
May 07, 2019 14:39:21.241 [0x14d08fbfd700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:39:21.242 [0x14d08fbfd700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=c2f87a95-24aa-4494-a3e7-07e5887b4cee (0, No error) (Empty reply from server)
May 07, 2019 14:40:02.401 [0x14d08c2e0700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.160000 seconds
May 07, 2019 14:40:21.238 [0x14d08fbfd700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:40:21.238 [0x14d08fbfd700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=19ee2c3b-73fd-4c08-96c4-227d1d159996 (0, No error) (Empty reply from server)
May 07, 2019 14:40:28.722 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 7940
May 07, 2019 14:40:32.259 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 7876
May 07, 2019 14:40:56.421 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 7916
May 07, 2019 14:40:58.807 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 7947
May 07, 2019 14:41:01.126 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 7992
May 07, 2019 14:41:02.651 [0x14d0cd29a700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 14:41:03.436 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 8024
May 07, 2019 14:42:02.903 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 14:43:03.172 [0x14d0cd49b700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 14:46:15.900 [0x14d08cee6700] WARN - MyPlex: we have an existing connectivity refresh request for async identifier f010af3b-9d8b-45fe-92ce-7f87509ff9a6, but it has expired so we will try again.
May 07, 2019 14:46:21.244 [0x14d08cee6700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:46:21.244 [0x14d08cee6700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=0d46ebdd-33af-46d6-baca-51a593ac1beb (0, No error) (Empty reply from server)
May 07, 2019 14:48:55.455 [0x14d08fdfe700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 14:48:55.455 [0x14d08fdfe700] WARN - HTTP error requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Empty reply from server)
May 07, 2019 14:48:55.457 [0x14d08fdfe700] ERROR - Error parsing content.
May 07, 2019 14:48:55.457 [0x14d08fdfe700] ERROR - Error parsing XML: Error parsing file.
May 07, 2019 14:49:04.480 [0x14d0cc694700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 14:51:15.901 [0x14d08e1f0700] WARN - MyPlex: we have an existing connectivity refresh request for async identifier ea9ad2c5-c7fd-437a-b78e-f365b2e54821, but it has expired so we will try again.
May 07, 2019 14:53:05.411 [0x14d0cd69c700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.160000 seconds
May 07, 2019 14:56:15.900 [0x14d08e9f4700] WARN - MyPlex: we have an existing connectivity refresh request for async identifier 27bccfcc-eaf4-4b34-ad61-acb2386fac53, but it has expired so we will try again.
May 07, 2019 14:57:15.900 [0x14d08e1f0700] WARN - MyPlex: we have an existing connectivity refresh request for async identifier 9e0733db-f12f-43f8-8a92-1c523218087c, but it has expired so we will try again.
May 07, 2019 14:58:15.902 [0x14d08e1f0700] WARN - MyPlex: we have an existing connectivity refresh request for async identifier c69adbe3-34a9-43c3-90e2-22da8fc1606b, but it has expired so we will try again.
May 07, 2019 14:58:36.641 [0x14d0cc292700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.170000 seconds
May 07, 2019 15:00:06.977 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:02:21.244 [0x14d08e9f4700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 15:02:21.244 [0x14d08e9f4700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=e9d4bfe3-284a-4413-8a4a-1a7d0ad87969 (0, No error) (Empty reply from server)
May 07, 2019 15:04:21.246 [0x14d08fbfd700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 15:04:21.246 [0x14d08fbfd700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=938051eb-a5ae-4c50-8aea-cc7ec2daadbb (0, No error) (Empty reply from server)
May 07, 2019 15:05:21.250 [0x14d08c8e3700] ERROR - Error issuing curl_easy_perform(handle): 52
May 07, 2019 15:05:21.250 [0x14d08c8e3700] WARN - HTTP error requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=c24c9314-b692-4dbc-8dd4-4486911dee5f (0, No error) (Empty reply from server)
May 07, 2019 15:08:08.728 [0x14d0ccc97700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.180000 seconds
May 07, 2019 15:08:38.855 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:09:08.986 [0x14d08c2e0700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 15:12:50.185 [0x14d0273f9700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 15:14:55.617 [0x14d0cc292700] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 07, 2019 15:16:40.614 [0x14d0cc895700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.160000 seconds
May 07, 2019 15:16:43.376 [0x14d027fff700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
May 07, 2019 15:29:43.280 [0x14d08c2e0700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:31:43.084 [0x14d0277fb700] INFO - Library section 3 (Movies) will be updated because of a change in '"/media/Movies"/Storm Boy (2019)'
May 07, 2019 15:31:43.754 [0x14d0cce98700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.160000 seconds
May 07, 2019 15:32:13.860 [0x14d0cd29a700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 15:32:43.975 [0x14d08d4e9700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 15:33:14.088 [0x14d08fdfe700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:33:44.202 [0x14d0cca96700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 15:40:37.044 [0x14d0277fb700] INFO - Library section 3 (Movies) will be updated because of a change in '"/media/Movies/Storm Boy (2019)"/Storm Boy (2019) [] - Bluray-720p.mkv.partial~'
May 07, 2019 15:41:45.868 [0x14d0cd099700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:42:15.980 [0x14d0ce153700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:43:16.195 [0x14d0cd099700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:43:56.467 [0x14d0277fb700] INFO - Library section 3 (Movies) will be updated because of a change in '"/media/Movies"/Rupert, Rupert & Rupert (2019)'
May 07, 2019 15:44:46.541 [0x14d0cc493700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.150000 seconds
May 07, 2019 15:45:16.665 [0x14d0cd099700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 15:45:46.783 [0x14d0cca96700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.140000 seconds
May 07, 2019 15:47:17.127 [0x14d08fdfe700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 15:47:47.242 [0x14d0ce153700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:48:17.360 [0x14d0ce354700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:49:17.587 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 15:51:48.119 [0x14d0ce957700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:52:48.346 [0x14d0ce153700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:54:18.696 [0x14d0cc292700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 15:54:48.822 [0x14d08ffff700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.130000 seconds
May 07, 2019 15:55:18.950 [0x14d08c4e1700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.170000 seconds
May 07, 2019 15:55:49.073 [0x14d0ce354700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 15:56:19.182 [0x14d08d4e9700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 16:00:20.131 [0x14d0cd49b700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.120000 seconds
May 07, 2019 16:06:21.538 [0x14d08cae4700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 16:12:22.803 [0x14d0cc694700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 16:14:54.568 [0x14d0cd69c700] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 07, 2019 16:24:04.110 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 8473
May 07, 2019 16:24:19.553 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 8518
May 07, 2019 16:24:21.055 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 8546
May 07, 2019 16:24:23.338 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 8569
May 07, 2019 16:24:25.207 [0x14d0ceb58700] WARN - JobManager: Could not find job for handle 8596
May 07, 2019 16:24:25.253 [0x14d0cca96700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 1.260000 seconds
May 07, 2019 16:24:27.925 [0x14d08cce5700] INFO - AutoUpdate: no updates available
May 07, 2019 16:25:21.336 [0x14d08cee6700] INFO - AutoUpdate: no updates available
May 07, 2019 16:29:54.103 [0x14d0ce555700] ERROR - EventSource: Retrying in 15 seconds.
May 07, 2019 16:30:29.103 [0x14d0ce756700] ERROR - EventSource: Retrying in 30 seconds.
May 07, 2019 16:31:19.104 [0x14d0ce756700] ERROR - EventSource: Retrying in 60 seconds.
May 07, 2019 16:32:34.457 [0x14d0ce555700] ERROR - EventSource: Retrying in 120 seconds.
May 07, 2019 16:34:34.607 [0x14d0ce756700] ERROR - EventSource: Retrying in 240 seconds.
May 07, 2019 16:36:57.878 [0x14d0ce354700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 16:45:55.577 [0x14d08cee6700] INFO - AutoUpdate: no updates available
May 07, 2019 16:45:59.783 [0x14d08c2e0700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.170000 seconds
May 07, 2019 16:46:00.043 [0x14d08c6e2700] INFO - AutoUpdate: no updates available
May 07, 2019 16:46:03.945 [0x14d08cee6700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 16:46:30.035 [0x14d08eff7700] INFO - AutoUpdate: no updates available
May 07, 2019 16:46:35.775 [0x14d08eff7700] INFO - AutoUpdate: no updates available
May 07, 2019 16:46:43.328 [0x14d08d2e8700] INFO - AutoUpdate: no updates available
May 07, 2019 16:47:03.334 [0x14d08c8e3700] INFO - AutoUpdate: no updates available
May 07, 2019 16:47:06.864 [0x14d08e7f3700] WARN - SLOW QUERY: It took 220.000000 ms to retrieve 6 items.
May 07, 2019 16:47:31.357 [0x14d08eff7700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 16:50:40.691 [0x14d08cee6700] INFO - AutoUpdate: no updates available
May 07, 2019 16:56:01.900 [0x14d0cc694700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.110000 seconds
May 07, 2019 16:56:11.336 [0x14d08eff7700] INFO - AutoUpdate: no updates available
May 07, 2019 16:56:11.878 [0x14d08cce5700] INFO - AutoUpdate: no updates available
May 07, 2019 16:56:17.222 [0x14d08e5f2700] INFO - AutoUpdate: no updates available
May 07, 2019 16:56:17.792 [0x14d08e9f4700] INFO - AutoUpdate: no updates available
May 07, 2019 16:56:18.699 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:42722] 200 PUT /:/prefs?logDebug=1 (28 live) TLS GZIP 1ms 274 bytes (pipelined: 6)
May 07, 2019 16:56:19.895 [0x14d08ffff700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:21.352 [0x14d08e1f0700] DEBUG - Transcoder segment range: 209 - 1869 (1869)
May 07, 2019 16:56:21.519 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:21.519 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:21.519 [0x14d08e9f4700] DEBUG - Request: [73.9.170.41:25293 (WAN)] GET /library/sections (29 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:21.519 [0x14d08cee6700] DEBUG - Request: [73.9.170.41:42722 (WAN)] GET /music/iTunes (29 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:21.519 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:21.519 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:21.519 [0x14d08fbfd700] DEBUG - Request: [73.9.170.41:14639 (WAN)] GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeExternalMedia=1&excludePlaylists=1 (28 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:21.519 [0x14d08c6e2700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /media/providers (28 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:21.520 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:42722] 404 GET /music/iTunes (28 live) TLS GZIP 0ms 458 bytes (pipelined: 7)
May 07, 2019 16:56:21.520 [0x14d08fbfd700] DEBUG - HubCache: Adding '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1' to the cache (1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1).
May 07, 2019 16:56:21.520 [0x14d08fbfd700] DEBUG - HubCache: Adding '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1' to the cache (1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1).
May 07, 2019 16:56:21.521 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 200 GET /media/providers (28 live) TLS GZIP 2ms 3399 bytes (pipelined: 7)
May 07, 2019 16:56:21.521 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:25293] 200 GET /library/sections (28 live) TLS GZIP 2ms 1140 bytes (pipelined: 2)
May 07, 2019 16:56:21.621 [0x14d08fbfd700] DEBUG - HubCache: Computed '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1' in 101 ms.
May 07, 2019 16:56:21.621 [0x14d08fbfd700] DEBUG - HubCache: 60 hubs cached, 25.7% hit ratio.
May 07, 2019 16:56:21.621 [0x14d08fbfd700] DEBUG - HubCache: Computed '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1' in 101 ms.
May 07, 2019 16:56:21.621 [0x14d08fbfd700] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1' from the cache.
May 07, 2019 16:56:21.621 [0x14d08fbfd700] DEBUG - HubCache: Retrieving '1/home.movies.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1' from the cache.
May 07, 2019 16:56:21.622 [0x14d08fbfd700] DEBUG - HubCache: Adding '1/home.television.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1' to the cache (1/home.television.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1).
May 07, 2019 16:56:21.623 [0x14d08fbfd700] DEBUG - It took 0.000000 ms to retrieve 200 items.
May 07, 2019 16:56:21.643 [0x14d08fbfd700] DEBUG - HubCache: Computed '1/home.television.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1' in 21 ms.
May 07, 2019 16:56:21.643 [0x14d08fbfd700] DEBUG - HubCache: Retrieving '1/home.videos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' from the cache.
May 07, 2019 16:56:21.643 [0x14d08fbfd700] DEBUG - HubCache: Retrieving '1/home.photos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' from the cache.
May 07, 2019 16:56:21.643 [0x14d08fbfd700] DEBUG - HubCache: Retrieving '1/home.music.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' from the cache.
May 07, 2019 16:56:21.643 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.643 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322667 (autoselect: 0 language: en)
May 07, 2019 16:56:21.643 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.643 [0x14d08fbfd700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:21.643 [0x14d08fbfd700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:21.643 [0x14d08fbfd700] DEBUG - Audio Stream: 680638, Subtitle Stream: 680639
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322292 (autoselect: 0 language: en)
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Audio Stream: 679860, Subtitle Stream: 679861
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322172 (autoselect: 0 language: en)
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Audio Stream: 679617, Subtitle Stream: 679618
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322617 (autoselect: 0 language: en)
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Audio Stream: 680535, Subtitle Stream: -1
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 321309 (autoselect: 0 language: en)
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.644 [0x14d08fbfd700] DEBUG - Audio Stream: 677511, Subtitle Stream: -1
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 320260 (autoselect: 0 language: en)
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - Audio Stream: 672777, Subtitle Stream: -1
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 318787 (autoselect: 0 language: en)
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - Audio Stream: 667741, Subtitle Stream: -1
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 319933 (autoselect: 0 language: en)
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.645 [0x14d08fbfd700] DEBUG - Audio Stream: 671305, Subtitle Stream: -1
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322667 (autoselect: 0 language: en)
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Audio Stream: 680638, Subtitle Stream: 680639
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322660 (autoselect: 0 language: en)
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Audio Stream: 680629, Subtitle Stream: 680630
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322659 (autoselect: 0 language: en)
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Audio Stream: 680626, Subtitle Stream: -1
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322658 (autoselect: 0 language: en)
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.646 [0x14d08fbfd700] DEBUG - Audio Stream: 680624, Subtitle Stream: -1
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322657 (autoselect: 0 language: en)
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - Audio Stream: 680623, Subtitle Stream: -1
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322633 (autoselect: 0 language: en)
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - Audio Stream: 680575, Subtitle Stream: -1
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322632 (autoselect: 0 language: en)
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - Audio Stream: 680572, Subtitle Stream: -1
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322631 (autoselect: 0 language: en)
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - Audio Stream: 680569, Subtitle Stream: -1
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322630 (autoselect: 0 language: en)
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.647 [0x14d08fbfd700] DEBUG - Audio Stream: 680566, Subtitle Stream: -1
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322629 (autoselect: 0 language: en)
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Audio Stream: 680564, Subtitle Stream: -1
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322621 (autoselect: 0 language: en)
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Audio Stream: 680544, Subtitle Stream: 680546
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322618 (autoselect: 0 language: en)
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Audio Stream: 680537, Subtitle Stream: -1
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322617 (autoselect: 0 language: en)
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Audio Stream: 680535, Subtitle Stream: -1
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322614 (autoselect: 0 language: en)
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.648 [0x14d08fbfd700] DEBUG - Audio Stream: 680529, Subtitle Stream: -1
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322612 (autoselect: 0 language: en)
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - Audio Stream: 680525, Subtitle Stream: -1
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322611 (autoselect: 0 language: en)
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - Audio Stream: 680523, Subtitle Stream: -1
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322608 (autoselect: 0 language: en)
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.649 [0x14d08fbfd700] DEBUG - Audio Stream: 680515, Subtitle Stream: -1
May 07, 2019 16:56:21.652 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:14639] 200 GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeExternalMedia=1&excludePlaylists=1 (28 live) TLS GZIP 132ms 16880 bytes (pipelined: 8)
May 07, 2019 16:56:21.897 [0x14d0cd29a700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:21.968 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:21.968 [0x14d08eff7700] DEBUG - Request: [73.9.170.41:14639 (WAN)] GET /hubs/home/recentlyAdded?type=1&includeExternalMedia=1 (28 live) TLS Page 16-50 GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:21.969 [0x14d08eff7700] DEBUG - Setting container serialization range to [16, 50] (total=-1)
May 07, 2019 16:56:21.974 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:21.974 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:21.975 [0x14d08cce5700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /hubs/home/recentlyAdded?type=8&includeExternalMedia=1 (28 live) TLS Page 16-50 GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:21.975 [0x14d08d2e8700] DEBUG - Request: [73.9.170.41:25293 (WAN)] GET /hubs/home/recentlyAdded?type=2&includeExternalMedia=1 (28 live) TLS Page 16-50 GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:21.975 [0x14d08cce5700] DEBUG - Setting container serialization range to [16, 50] (total=-1)
May 07, 2019 16:56:21.978 [0x14d08d2e8700] DEBUG - It took 0.000000 ms to retrieve 200 items.
May 07, 2019 16:56:21.979 [0x14d08cce5700] DEBUG - Setting container serialization range to [16, 50] (total=1794)
May 07, 2019 16:56:21.981 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 200 GET /hubs/home/recentlyAdded?type=8&includeExternalMedia=1 (28 live) TLS GZIP Page 16-50 6ms 3728 bytes (pipelined: 8)
May 07, 2019 16:56:21.983 [0x14d08eff7700] DEBUG - Setting container serialization range to [16, 50] (total=3737)
May 07, 2019 16:56:21.986 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:14639] 200 GET /hubs/home/recentlyAdded?type=1&includeExternalMedia=1 (28 live) TLS GZIP Page 16-50 18ms 12650 bytes (pipelined: 9)
May 07, 2019 16:56:21.998 [0x14d08d2e8700] DEBUG - Setting container serialization range to [16, 50] (total=-1)
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322608 (autoselect: 0 language: en)
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - Audio Stream: 680515, Subtitle Stream: -1
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322607 (autoselect: 0 language: en)
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - Audio Stream: 680513, Subtitle Stream: -1
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322606 (autoselect: 0 language: en)
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - Audio Stream: 680509, Subtitle Stream: 680510
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322605 (autoselect: 0 language: en)
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:21.999 [0x14d08d2e8700] DEBUG - Audio Stream: 680507, Subtitle Stream: -1
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322602 (autoselect: 0 language: en)
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Audio Stream: 680501, Subtitle Stream: -1
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322600 (autoselect: 0 language: en)
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Audio Stream: 680496, Subtitle Stream: -1
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322601 (autoselect: 0 language: en)
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Audio Stream: 680498, Subtitle Stream: 680499
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322597 (autoselect: 0 language: en)
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Audio Stream: 680490, Subtitle Stream: -1
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322592 (autoselect: 0 language: en)
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.000 [0x14d08d2e8700] DEBUG - Audio Stream: 680481, Subtitle Stream: -1
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322589 (autoselect: 0 language: en)
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Audio Stream: 680474, Subtitle Stream: 680475
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322588 (autoselect: 0 language: en)
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Audio Stream: 680471, Subtitle Stream: 680472
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322525 (autoselect: 0 language: en)
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Audio Stream: 680347, Subtitle Stream: -1
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322499 (autoselect: 0 language: en)
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Audio Stream: 680291, Subtitle Stream: -1
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322493 (autoselect: 0 language: en)
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.001 [0x14d08d2e8700] DEBUG - Audio Stream: 680277, Subtitle Stream: -1
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322619 (autoselect: 0 language: en)
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Audio Stream: 680540, Subtitle Stream: -1
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322411 (autoselect: 0 language: en)
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Audio Stream: 680107, Subtitle Stream: -1
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322408 (autoselect: 0 language: en)
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Audio Stream: 680099, Subtitle Stream: -1
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322407 (autoselect: 0 language: en)
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Audio Stream: 680096, Subtitle Stream: 680097
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322406 (autoselect: 0 language: en)
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.002 [0x14d08d2e8700] DEBUG - Audio Stream: 680094, Subtitle Stream: -1
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322405 (autoselect: 0 language: en)
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - Audio Stream: 680091, Subtitle Stream: -1
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322404 (autoselect: 0 language: en)
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - Audio Stream: 680088, Subtitle Stream: -1
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322403 (autoselect: 0 language: en)
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - Audio Stream: 680084, Subtitle Stream: -1
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322402 (autoselect: 0 language: en)
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - Audio Stream: 680082, Subtitle Stream: -1
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322401 (autoselect: 0 language: en)
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.003 [0x14d08d2e8700] DEBUG - Audio Stream: 680075, Subtitle Stream: -1
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322396 (autoselect: 0 language: en)
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - Audio Stream: 680064, Subtitle Stream: -1
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322410 (autoselect: 0 language: en)
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - Audio Stream: 680103, Subtitle Stream: -1
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322392 (autoselect: 0 language: en)
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - Audio Stream: 680055, Subtitle Stream: -1
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322390 (autoselect: 0 language: en)
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - Audio Stream: 680050, Subtitle Stream: -1
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322377 (autoselect: 0 language: en)
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.004 [0x14d08d2e8700] DEBUG - Audio Stream: 680036, Subtitle Stream: -1
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322374 (autoselect: 0 language: en)
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - Audio Stream: 680028, Subtitle Stream: -1
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322373 (autoselect: 0 language: en)
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - Audio Stream: 680026, Subtitle Stream: -1
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322372 (autoselect: 0 language: en)
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - Audio Stream: 680022, Subtitle Stream: -1
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322376 (autoselect: 0 language: en)
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - Audio Stream: 680033, Subtitle Stream: -1
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - Selecting best audio stream for part ID 322362 (autoselect: 0 language: en)
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:22.005 [0x14d08d2e8700] DEBUG - Audio Stream: 680002, Subtitle Stream: -1
May 07, 2019 16:56:22.007 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:25293] 200 GET /hubs/home/recentlyAdded?type=2&includeExternalMedia=1 (28 live) TLS GZIP Page 16-50 32ms 12600 bytes (pipelined: 3)
May 07, 2019 16:56:22.620 [0x14d0ce555700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:56:22.621 [0x14d08e5f2700] DEBUG - Request: [66.186.97.26:6346 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (28 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:56:22.621 [0x14d08e5f2700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:56:22.626 [0x14d08e5f2700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:56:22.626 [0x14d08e5f2700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:56:22.626 [0x14d08e5f2700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:56:22.626 [0x14d08e5f2700] DEBUG - [Now] Profile is Web
May 07, 2019 16:56:22.626 [0x14d08e5f2700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:56:22.626 [0x14d08e5f2700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:56:22.627 [0x14d0cc493700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:22.628 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:6346] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (28 live) TLS GZIP 7ms 915 bytes (pipelined: 12)
May 07, 2019 16:56:22.648 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:22.649 [0x14d08d7eb700] DEBUG - Request: [192.168.20.248:44358 (Allowed Network (Subnet))] GET /status/sessions (28 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:56:22.649 [0x14d08d7eb700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:22.650 [0x14d0ce555700] DEBUG - Completed: [192.168.20.248:44358] 200 GET /status/sessions (28 live) TLS 1ms 6447 bytes (pipelined: 1)
May 07, 2019 16:56:23.898 [0x14d0cc895700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:25.901 [0x14d0ce153700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:27.902 [0x14d08cae4700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:29.903 [0x14d0cc493700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:31.904 [0x14d08ffff700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:32.624 [0x14d0ce555700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:56:32.625 [0x14d08cce5700] DEBUG - Request: [66.186.97.26:6346 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (26 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:56:32.626 [0x14d08cce5700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:56:32.631 [0x14d08cce5700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:56:32.631 [0x14d08cce5700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:56:32.631 [0x14d08cce5700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:56:32.632 [0x14d08cce5700] DEBUG - [Now] Profile is Web
May 07, 2019 16:56:32.632 [0x14d08cce5700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:56:32.632 [0x14d08cce5700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:56:32.635 [0x14d0ce756700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:56:32.636 [0x14d08c8e3700] DEBUG - Request: [66.186.97.26:45258 (WAN)] GET /video/:/transcode/universal/ping?session=ohzr2upkzsf9khp2ab5zegjy (27 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:56:32.636 [0x14d0cc895700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:32.636 [0x14d08c8e3700] DEBUG - Found session GUID of ohzr2upkzsf9khp2ab5zegjy in session start.
May 07, 2019 16:56:32.637 [0x14d08c8e3700] DEBUG - [Now] Freshening session based on ping for 4ozhkusgp2yg3jesnvmzg9sa_Video
May 07, 2019 16:56:32.637 [0x14d08c8e3700] DEBUG - Received transcode session ping for session ohzr2upkzsf9khp2ab5zegjy.
May 07, 2019 16:56:32.637 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:45258] 200 GET /video/:/transcode/universal/ping?session=ohzr2upkzsf9khp2ab5zegjy (27 live) TLS GZIP 1ms 274 bytes (pipelined: 1)
May 07, 2019 16:56:32.638 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:6346] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (28 live) TLS GZIP 13ms 904 bytes (pipelined: 13)
May 07, 2019 16:56:32.654 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:32.654 [0x14d08e5f2700] DEBUG - Request: [192.168.20.248:44360 (Allowed Network (Subnet))] GET /status/sessions (28 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:56:32.654 [0x14d08e5f2700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:32.655 [0x14d0ce555700] DEBUG - Completed: [192.168.20.248:44360] 200 GET /status/sessions (28 live) TLS 1ms 6429 bytes (pipelined: 1)
May 07, 2019 16:56:33.905 [0x14d08d4e9700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:33.970 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:33.970 [0x14d08d7eb700] DEBUG - Request: [73.9.170.41:25293 (WAN)] POST /playQueues?type=video&continuous=1&uri=server%3A%2F%2Faafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F134539&repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:33.971 [0x14d08d7eb700] DEBUG - PlayQueue: Converted 'server://aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/com.plexapp.plugins.library/library/metadata/134539' to 'library://x/item/%2Flibrary%2Fmetadata%2F134539'
May 07, 2019 16:56:33.971 [0x14d08d7eb700] DEBUG - PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
May 07, 2019 16:56:33.976 [0x14d08d7eb700] DEBUG - PlayQueue: Start index: 0 End index: 1 Count: 2
May 07, 2019 16:56:33.976 [0x14d08d7eb700] DEBUG - PlayQueue: 2 generated IDs compressed down to a 28 byte blob.
May 07, 2019 16:56:33.980 [0x14d08d7eb700] DEBUG - PlayQueue: Added 2 items in 0.0 sec.
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - Selecting best audio stream for part ID 322292 (autoselect: 0 language: en)
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - Audio Stream: 679860, Subtitle Stream: 679861
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - Selecting best audio stream for part ID 322660 (autoselect: 0 language: en)
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:33.984 [0x14d08d7eb700] DEBUG - Audio Stream: 680629, Subtitle Stream: 680630
May 07, 2019 16:56:33.984 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:25293] 200 POST /playQueues?type=video&continuous=1&uri=server%3A%2F%2Faafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F134539&repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (26 live) TLS GZIP 14ms 1684 bytes (pipelined: 4)
May 07, 2019 16:56:34.162 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:34.162 [0x14d08e7f3700] DEBUG - Request: [73.9.170.41:25293 (WAN)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F134539&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=wan&maxVideoBitrate=4000&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=0&mediaBufferSize=102400&session=5inl2zux2ffbat0u35k1p6w1&subtitles=burn&Accept-Language=en (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:34.162 [0x14d08e7f3700] DEBUG - Found session GUID of 5inl2zux2ffbat0u35k1p6w1 in session start.
May 07, 2019 16:56:34.163 [0x14d08e7f3700] DEBUG - TranscodeUniversalRequest: using augmented profile Web
May 07, 2019 16:56:34.163 [0x14d08e7f3700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/134539?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=364mfnveg0c1e4tbnubuc2mc
May 07, 2019 16:56:34.164 [0x14d08e7f3700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:34.169 [0x14d08e7f3700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:34.169 [0x14d08e7f3700] DEBUG - Selecting best audio stream for part ID 322292 (autoselect: 0 language: en)
May 07, 2019 16:56:34.169 [0x14d08e7f3700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:34.169 [0x14d08e7f3700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:34.169 [0x14d08e7f3700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:34.169 [0x14d08e7f3700] DEBUG - Audio Stream: 679860, Subtitle Stream: 679861
May 07, 2019 16:56:34.170 [0x14d08e7f3700] DEBUG - Scaled up video bitrate to 7572Kbps based on 1.500000x fudge factor.
May 07, 2019 16:56:34.170 [0x14d08e7f3700] DEBUG - MDE: Selected protocol dash; container: mp4
May 07, 2019 16:56:34.170 [0x14d08e7f3700] DEBUG - MDE: analyzing media item 321190
May 07, 2019 16:56:34.170 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: Direct Play is disabled
May 07, 2019 16:56:34.170 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: media must be transcoded in order to use the dash protocol
May 07, 2019 16:56:34.170 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: selected subtitle cannot be converted to a compatible format, burning into video stream
May 07, 2019 16:56:34.171 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: Direct Streaming is disabled, so video stream will be transcoded
May 07, 2019 16:56:34.171 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: no remuxable profile found, so video stream will be transcoded
May 07, 2019 16:56:34.171 [0x14d08e7f3700] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
May 07, 2019 16:56:34.171 [0x14d08e7f3700] DEBUG - Scaled up video bitrate to 7572Kbps based on 1.500000x fudge factor.
May 07, 2019 16:56:34.171 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: Audio Direct Streaming is disabled, so video's audio stream will be transcoded
May 07, 2019 16:56:34.171 [0x14d08e7f3700] DEBUG - MDE: Cannot direct stream audio stream due to profile or setting limitations
May 07, 2019 16:56:34.171 [0x14d08e7f3700] DEBUG - MDE: Black Clover - S1 E81 - The Life of a Certain Man: selected media 0 / 321190
May 07, 2019 16:56:34.171 [0x14d08e7f3700] DEBUG - Streaming Resource: Calculated bandwidth of 4469kbps exceeds bandwidth limit. Changing decision parameters provided by client to fit bandwidth limit of 4000kbps
May 07, 2019 16:56:34.171 [0x14d08e7f3700] DEBUG - Streaming Resource: Determining preferred transcode encoders through transcode only decision.
May 07, 2019 16:56:34.171 [0x14d08e7f3700] DEBUG - Scaled up video bitrate to 7572Kbps based on 1.500000x fudge factor.
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - Scaled up video bitrate to 7572Kbps based on 1.500000x fudge factor.
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - Streaming Resource: Reducing playback quality for 3809kbps stream bitrate: video resolution to 1280x720, audio channels to 2, quality to 14
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - Scaled up video bitrate to 7572Kbps based on 1.500000x fudge factor.
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - Scaled maximum bitrate for resolution reduction to 3365Kbps.
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: Selected protocol dash; container: mp4
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: analyzing media item 321190
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: Direct Play is disabled
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: media must be transcoded in order to use the dash protocol
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: selected subtitle cannot be converted to a compatible format, burning into video stream
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: Direct Streaming is disabled, so video stream will be transcoded
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: no remuxable profile found, so video stream will be transcoded
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - Scaled up video bitrate to 7572Kbps based on 1.500000x fudge factor.
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - Scaled maximum bitrate for resolution reduction to 3365Kbps.
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: E81 - The Life of a Certain Man: Audio Direct Streaming is disabled, so video's audio stream will be transcoded
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: Cannot direct stream audio stream due to profile or setting limitations
May 07, 2019 16:56:34.172 [0x14d08e7f3700] DEBUG - MDE: Black Clover - S1 E81 - The Life of a Certain Man: selected media 0 / 321190
May 07, 2019 16:56:34.173 [0x14d08e7f3700] DEBUG - Streaming Resource: Adding session 0x14d05c3c97a0:364mfnveg0c1e4tbnubuc2mc which is using 3659kbps of WAN bandwidth.  Used is now 12328kbps
May 07, 2019 16:56:34.173 [0x14d08e7f3700] DEBUG - Streaming Resource: Adding session 0x14d05c3c97a0:364mfnveg0c1e4tbnubuc2mc which is using transcoder slot.  Used slots is now 2
May 07, 2019 16:56:34.173 [0x14d08e7f3700] DEBUG - Streaming Resource: Added session 0x14d05c3c97a0:364mfnveg0c1e4tbnubuc2mc
May 07, 2019 16:56:34.173 [0x14d08e7f3700] DEBUG - Streaming Resource: Reached Decision id=134539 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=321190 part=(id=322292 decision=transcode container=mp4 protocol=dash streams=(Video=(id=679859 decision=transcode bitrate=3365 encoder=libx264 width=1280 height=720) Audio=(id=679860 decision=transcode bitrate=119 encoder=aac channels=2 rate=44100) Subtitle=(id=679861 decision=burn languageCode=eng location=embedded))))
May 07, 2019 16:56:34.174 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:25293] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F134539&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=wan&maxVideoBitrate=4000&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=0&mediaBufferSize=102400&session=5inl2zux2ffbat0u35k1p6w1&subtitles=burn&Accept-Language=en (26 live) TLS GZIP 12ms 1543 bytes (pipelined: 5)
May 07, 2019 16:56:34.318 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:34.318 [0x14d08fbfd700] DEBUG - Request: [73.9.170.41:25293 (WAN)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F134539&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=wan&maxVideoBitrate=4000&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=0&mediaBufferSize=102400&session=5inl2zux2ffbat0u35k1p6w1&subtitles=burn&Accept-Language=en (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:34.318 [0x14d08fbfd700] DEBUG - Found session GUID of 5inl2zux2ffbat0u35k1p6w1 in session start.
May 07, 2019 16:56:34.318 [0x14d08fbfd700] DEBUG - TranscodeUniversalRequest: using augmented profile Web
May 07, 2019 16:56:34.318 [0x14d08fbfd700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/134539?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=364mfnveg0c1e4tbnubuc2mc
May 07, 2019 16:56:34.318 [0x14d08fbfd700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:34.320 [0x14d08fbfd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:34.320 [0x14d08fbfd700] DEBUG - Selecting best audio stream for part ID 322292 (autoselect: 0 language: en)
May 07, 2019 16:56:34.320 [0x14d08fbfd700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:34.320 [0x14d08fbfd700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:34.320 [0x14d08fbfd700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:34.321 [0x14d08fbfd700] DEBUG - Audio Stream: 679860, Subtitle Stream: 679861
May 07, 2019 16:56:34.321 [0x14d08fbfd700] DEBUG - Found session GUID of 5inl2zux2ffbat0u35k1p6w1 in session start.
May 07, 2019 16:56:34.321 [0x14d08fbfd700] DEBUG - Cleaning directory for session 5inl2zux2ffbat0u35k1p6w1 ()
May 07, 2019 16:56:34.321 [0x14d08fbfd700] DEBUG - Starting a transcode session 5inl2zux2ffbat0u35k1p6w1 at offset -1.0 (state=3)
May 07, 2019 16:56:34.321 [0x14d08fbfd700] DEBUG - [Universal] Using local file path instead of URL: /media/Anime/TV/Black Clover/Season 1/Black Clover - S01E81 - The Life of a Certain Man HDTV-1080p.mkv
May 07, 2019 16:56:34.321 [0x14d08fbfd700] DEBUG - TPU: hardware transcoding: zero-copy support not present
May 07, 2019 16:56:34.322 [0x14d08fbfd700] DEBUG - Job running: EAE_ROOT='/tmp/pms-bbb8108d-6d88-4dfd-aaff-cdfbf004b1a3/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/392df4f-2183-linux-x86_64/' XDG_CACHE_HOME='/config/Library/Application Support/Plex Media Server/Cache' XDG_DATA_HOME='/usr/lib/plexmediaserver/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Transcoder' '-codec:0' 'h264' '-codec:1' 'aac_lc' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/media/Anime/TV/Black Clover/Season 1/Black Clover - S01E81 - The Life of a Certain Man HDTV-1080p.mkv' '-map_inlineass' '0:2' '-filter_complex' '[0:0]scale=w=1280:h=720[0];[0]format=pix_fmts=yuv420p|nv12[1];[1]inlineass=font_scale=1.000000:font_path=/usr/lib/plexmediaserver/Resources/Fonts/DejaVuSans-Regular.ttf:fontconfig_file=/usr/lib/plexmediaserver/Resources/fonts.conf:language=en[2]' '-filter_complex' '[0:1] aresample=async=1:ocl='\''stereo'\'':osr=44100[3]' '-map' '[2]' '-codec:0' 'libx264' '-crf:0' '23' '-maxrate:0' '3365k' '-bufsize:0' '6730k' '-r:0' '23.975999999999999' '-preset:0' 'veryfast' '-x264opts:0' 'subme=1:me_range=4:rc_lookahead=10:me=hex:8x8dct=0:partitions=none' '-force_key_frames:0' 'expr:gte(t,0+n_forced*3)' '-map' '[3]' '-metadata:s:1' 'language=jpn' '-codec:1' 'aac' '-b:1' '119k' '-f' 'dash' '-min_seg_duration' '3000000' '-skip_to_segment' '1' '-time_delta' '0.0625' '-manifest_name' 'http://127.0.0.1:32400/video/:/transcode/session/5inl2zux2ffbat0u35k1p6w1/08c53082-bb5b-493b-b85b-bf2e9c1a1425/manifest' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'dash' '-map' '0:2' '-f' 'null' '-codec' 'ass' 'nullfile' '-start_at_zero' '-copyts' '-vsync' 'cfr' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/5inl2zux2ffbat0u35k1p6w1/08c53082-bb5b-493b-b85b-bf2e9c1a1425/progress'
May 07, 2019 16:56:34.322 [0x14d08fbfd700] DEBUG - Jobs: Starting child process with pid 8795
May 07, 2019 16:56:34.328 [0x14d08d2e8700] DEBUG - Request: [127.0.0.1:59426 (Loopback)] PUT /video/:/transcode/session/5inl2zux2ffbat0u35k1p6w1/08c53082-bb5b-493b-b85b-bf2e9c1a1425/progress?status=startup (27 live) Signed-in Token (chaberman43)
May 07, 2019 16:56:34.328 [0x14d0ce756700] DEBUG - Completed: [127.0.0.1:59426] 204 PUT /video/:/transcode/session/5inl2zux2ffbat0u35k1p6w1/08c53082-bb5b-493b-b85b-bf2e9c1a1425/progress?status=startup (27 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-) 
May 07, 2019 16:56:34.328 [0x14d08eff7700] DEBUG - Request: [127.0.0.1:59426 (Loopback)] PUT /video/:/transcode/session/5inl2zux2ffbat0u35k1p6w1/08c53082-bb5b-493b-b85b-bf2e9c1a1425/progress?status=opening (27 live) Signed-in Token (chaberman43)
May 07, 2019 16:56:34.329 [0x14d0ce756700] DEBUG - Completed: [127.0.0.1:59426] 204 PUT /video/:/transcode/session/5inl2zux2ffbat0u35k1p6w1/08c53082-bb5b-493b-b85b-bf2e9c1a1425/progress?status=opening (27 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-) 
May 07, 2019 16:56:34.371 [0x14d08cee6700] DEBUG - Request: [127.0.0.1:59426 (Loopback)] PUT /video/:/transcode/session/5inl2zux2ffbat0u35k1p6w1/08c53082-bb5b-493b-b85b-bf2e9c1a1425/progress?status=opened (27 live) Signed-in Token (chaberman43)
May 07, 2019 16:56:34.372 [0x14d0ce555700] DEBUG - Completed: [127.0.0.1:59426] 204 PUT /video/:/transcode/session/5inl2zux2ffbat0u35k1p6w1/08c53082-bb5b-493b-b85b-bf2e9c1a1425/progress?status=opened (27 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
May 07, 2019 16:56:34.412 [0x14d08c8e3700] DEBUG - Request: [127.0.0.1:59426 (Loopback)] PUT /video/:/transcode/session/5inl2zux2ffbat0u35k1p6w1/08c53082-bb5b-493b-b85b-bf2e9c1a1425/progress/stream?index=0&id=0&codec=h264&type=video (27 live) Signed-in Token (chaberman43)
May 07, 2019 16:56:34.413 [0x14d0ce756700] DEBUG - Completed: [127.0.0.1:59426] 206 PUT /video/:/transcode/session/5inl2zux2ffbat0u35k1p6w1/08c53082-bb5b-493b-b85b-bf2e9c1a1425/progress/stream?index=0&id=0&codec=h264&type=video (27 live) 0ms 256 bytes (pipelined: 4) (range: bytes=0-) 
May 07, 2019 16:56:34.453 [0x14d08cce5700] ERROR - [Transcoder] Unknown decoder 'aac_lc'
May 07, 2019 16:56:34.496 [0x14d0ceb58700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 8795 is 1 (failure)
May 07, 2019 16:56:34.496 [0x14d0cd29a700] DEBUG - Streaming Resource: Terminating session 0x14d05c3c97a0:364mfnveg0c1e4tbnubuc2mc which is using 3659kbps of WAN bandwidth.  Used is now 8669kbps
May 07, 2019 16:56:34.496 [0x14d08fbfd700] DEBUG - Using default transcode duration of 120 minutes
May 07, 2019 16:56:34.496 [0x14d0cd29a700] DEBUG - Streaming Resource: Terminating session 0x14d05c3c97a0:364mfnveg0c1e4tbnubuc2mc which is using transcoder slot.  Used slots is now 1
May 07, 2019 16:56:34.497 [0x14d0cd29a700] DEBUG - Streaming Resource: Terminated session 0x14d05c3c97a0:364mfnveg0c1e4tbnubuc2mc with reason Conversion failed. The transcoder exited due to an error.
May 07, 2019 16:56:34.497 [0x14d08fbfd700] DEBUG - Started session successfully: 5inl2zux2ffbat0u35k1p6w1
May 07, 2019 16:56:34.497 [0x14d08c2e0700] DEBUG - Killing job.
May 07, 2019 16:56:34.497 [0x14d08c2e0700] DEBUG - Signalling job ID 8795 with 9
May 07, 2019 16:56:34.497 [0x14d08c2e0700] DEBUG - Job was already killed, not killing again.
May 07, 2019 16:56:34.497 [0x14d08c2e0700] DEBUG - Stopping transcode session 5inl2zux2ffbat0u35k1p6w1
May 07, 2019 16:56:34.497 [0x14d0ce354700] DEBUG - Cleaning directory for session 5inl2zux2ffbat0u35k1p6w1 (/config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-5inl2zux2ffbat0u35k1p6w1-08c53082-bb5b-493b-b85b-bf2e9c1a1425)
May 07, 2019 16:56:34.499 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:25293] 200 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F134539&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=wan&maxVideoBitrate=4000&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=0&mediaBufferSize=102400&session=5inl2zux2ffbat0u35k1p6w1&subtitles=burn&Accept-Language=en (26 live) TLS GZIP 180ms 1024 bytes (pipelined: 6)
May 07, 2019 16:56:34.500 [0x14d08c2e0700] DEBUG - Whacked session 5inl2zux2ffbat0u35k1p6w1, 1 remaining.
May 07, 2019 16:56:34.529 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:34.529 [0x14d08e1f0700] DEBUG - Request: [73.9.170.41:45993 (WAN)] GET /status/sessions (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:34.529 [0x14d08e1f0700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:34.531 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:45993] 200 GET /status/sessions (26 live) TLS GZIP 1ms 2707 bytes (pipelined: 3)
May 07, 2019 16:56:34.534 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:34.534 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:34.535 [0x14d08c6e2700] DEBUG - Request: [73.9.170.41:14639 (WAN)] GET /status/sessions (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:34.535 [0x14d08e9f4700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /status/sessions (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:34.535 [0x14d08c6e2700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:34.536 [0x14d08e9f4700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:34.538 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:14639] 200 GET /status/sessions (26 live) TLS GZIP 3ms 2800 bytes (pipelined: 10)
May 07, 2019 16:56:34.539 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 200 GET /status/sessions (26 live) TLS GZIP 4ms 2800 bytes (pipelined: 9)
May 07, 2019 16:56:34.574 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:34.574 [0x14d08e7f3700] DEBUG - Request: [73.9.170.41:25293 (WAN)] GET /status/sessions (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:34.574 [0x14d08e7f3700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:34.576 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:25293] 200 GET /status/sessions (26 live) TLS GZIP 1ms 2800 bytes (pipelined: 7)
May 07, 2019 16:56:34.601 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:34.601 [0x14d08d7eb700] DEBUG - Request: [73.9.170.41:45993 (WAN)] GET /status/sessions (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:34.601 [0x14d08d7eb700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:34.603 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:45993] 200 GET /status/sessions (26 live) TLS GZIP 2ms 2707 bytes (pipelined: 4)
May 07, 2019 16:56:34.612 [0x14d08d2e8700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/initial.mp4 (26 live) TLS GZIP Signed-in
May 07, 2019 16:56:34.612 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/initial.mp4 (26 live) TLS GZIP 0ms 458 bytes (pipelined: 10)
May 07, 2019 16:56:34.617 [0x14d08eff7700] DEBUG - Request: [73.9.170.41:14639 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/0.m4s (26 live) TLS GZIP Signed-in
May 07, 2019 16:56:34.617 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:34.617 [0x14d08cee6700] DEBUG - Request: [73.9.170.41:42722 (WAN)] GET /status/sessions (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:34.618 [0x14d08cee6700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:34.618 [0x14d08c2e0700] DEBUG - Cleaning directory for session 5inl2zux2ffbat0u35k1p6w1 (/config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-5inl2zux2ffbat0u35k1p6w1-08c53082-bb5b-493b-b85b-bf2e9c1a1425)
May 07, 2019 16:56:34.618 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:14639] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/0.m4s (26 live) TLS GZIP 1ms 458 bytes (pipelined: 11)
May 07, 2019 16:56:34.621 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:42722] 200 GET /status/sessions (26 live) TLS GZIP 3ms 2800 bytes (pipelined: 8)
May 07, 2019 16:56:34.634 [0x14d08c8e3700] DEBUG - Request: [73.9.170.41:25293 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/initial.mp4 (25 live) TLS GZIP Signed-in
May 07, 2019 16:56:34.635 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:25293] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/initial.mp4 (25 live) TLS GZIP 0ms 458 bytes (pipelined: 8)
May 07, 2019 16:56:34.666 [0x14d08cce5700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/0.m4s (27 live) TLS GZIP Signed-in
May 07, 2019 16:56:34.668 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/0.m4s (27 live) TLS GZIP 1ms 458 bytes (pipelined: 11)
May 07, 2019 16:56:35.540 [0x14d08e5f2700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/initial.mp4 (27 live) TLS GZIP Signed-in
May 07, 2019 16:56:35.541 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/initial.mp4 (27 live) TLS GZIP 0ms 458 bytes (pipelined: 12)
May 07, 2019 16:56:35.881 [0x14d08c6e2700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/0.m4s (27 live) TLS GZIP Signed-in
May 07, 2019 16:56:35.881 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/0.m4s (27 live) TLS GZIP 0ms 458 bytes (pipelined: 13)
May 07, 2019 16:56:35.906 [0x14d0cd29a700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:35.964 [0x14d08e9f4700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/0.m4s (27 live) TLS GZIP Signed-in
May 07, 2019 16:56:35.964 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/0.m4s (27 live) TLS GZIP 0ms 458 bytes (pipelined: 14)
May 07, 2019 16:56:36.039 [0x14d08e7f3700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/initial.mp4 (27 live) TLS GZIP Signed-in
May 07, 2019 16:56:36.039 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/initial.mp4 (27 live) TLS GZIP 0ms 458 bytes (pipelined: 15)
May 07, 2019 16:56:37.106 [0x14d08cee6700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/initial.mp4 (27 live) TLS GZIP Signed-in
May 07, 2019 16:56:37.106 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/initial.mp4 (27 live) TLS GZIP 0ms 458 bytes (pipelined: 16)
May 07, 2019 16:56:37.405 [0x14d0ce756700] DEBUG - WebSocket: client initiated close
May 07, 2019 16:56:37.405 [0x14d0ce756700] DEBUG - handleStreamRead code 335544539: short read
May 07, 2019 16:56:37.405 [0x14d0ce756700] DEBUG - NotificationStream: Removing because of error
May 07, 2019 16:56:37.877 [0x14d08cce5700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/initial.mp4 (25 live) TLS GZIP Signed-in
May 07, 2019 16:56:37.878 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/initial.mp4 (25 live) TLS GZIP 0ms 458 bytes (pipelined: 17)
May 07, 2019 16:56:37.907 [0x14d0cd69c700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:38.032 [0x14d08fbfd700] DEBUG - Transcoder segment range: 209 - 1870 (1870)
May 07, 2019 16:56:38.528 [0x14d08c6e2700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/0.m4s (26 live) TLS GZIP Signed-in
May 07, 2019 16:56:38.529 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/0.m4s (26 live) TLS GZIP 1ms 458 bytes (pipelined: 18)
May 07, 2019 16:56:38.811 [0x14d08e9f4700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/0.m4s (26 live) TLS GZIP Signed-in
May 07, 2019 16:56:38.811 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/0.m4s (26 live) TLS GZIP 0ms 458 bytes (pipelined: 19)
May 07, 2019 16:56:39.909 [0x14d0ce354700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:41.529 [0x14d08c8e3700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/0.m4s (26 live) TLS GZIP Signed-in
May 07, 2019 16:56:41.530 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/0.m4s (26 live) TLS GZIP 1ms 458 bytes (pipelined: 20)
May 07, 2019 16:56:41.912 [0x14d0cd69c700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:42.070 [0x14d08e1f0700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/initial.mp4 (25 live) TLS GZIP Signed-in
May 07, 2019 16:56:42.071 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/initial.mp4 (25 live) TLS GZIP 1ms 458 bytes (pipelined: 21)
May 07, 2019 16:56:42.621 [0x14d0ce555700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:56:42.622 [0x14d08e9f4700] DEBUG - Request: [66.186.97.26:6346 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (25 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:56:42.623 [0x14d08e9f4700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:56:42.627 [0x14d08e9f4700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:56:42.627 [0x14d08e9f4700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:56:42.627 [0x14d08e9f4700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:56:42.627 [0x14d08e9f4700] DEBUG - [Now] Profile is Web
May 07, 2019 16:56:42.627 [0x14d08e9f4700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:56:42.627 [0x14d08e9f4700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:56:42.628 [0x14d08ffff700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:42.630 [0x14d0ce555700] DEBUG - Completed: [66.186.97.26:6346] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (25 live) TLS GZIP 7ms 905 bytes (pipelined: 14)
May 07, 2019 16:56:42.650 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:42.650 [0x14d08e5f2700] DEBUG - Request: [192.168.20.248:44362 (Allowed Network (Subnet))] GET /status/sessions (26 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:56:42.650 [0x14d08e5f2700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:42.651 [0x14d0ce756700] DEBUG - Completed: [192.168.20.248:44362] 200 GET /status/sessions (26 live) TLS 1ms 6433 bytes (pipelined: 1)
May 07, 2019 16:56:42.809 [0x14d08d7eb700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/initial.mp4 (26 live) TLS GZIP Signed-in
May 07, 2019 16:56:42.810 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/initial.mp4 (26 live) TLS GZIP 0ms 458 bytes (pipelined: 22)
May 07, 2019 16:56:43.913 [0x14d0cc493700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:44.000 [0x14d08eff7700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/0.m4s (26 live) TLS GZIP Signed-in
May 07, 2019 16:56:44.000 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/0.m4s (26 live) TLS GZIP 0ms 458 bytes (pipelined: 23)
May 07, 2019 16:56:45.914 [0x14d0cd69c700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:46.180 [0x14d08e9f4700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/initial.mp4 (24 live) TLS GZIP Signed-in
May 07, 2019 16:56:46.180 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/0/initial.mp4 (24 live) TLS GZIP 0ms 458 bytes (pipelined: 24)
May 07, 2019 16:56:47.684 [0x14d08d7eb700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/initial.mp4 (23 live) TLS GZIP Signed-in
May 07, 2019 16:56:47.684 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/initial.mp4 (23 live) TLS GZIP 0ms 458 bytes (pipelined: 25)
May 07, 2019 16:56:47.915 [0x14d0ce153700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:48.777 [0x14d08e7f3700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/0.m4s (23 live) TLS GZIP Signed-in
May 07, 2019 16:56:48.777 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 404 GET /video/:/transcode/universal/dash/5inl2zux2ffbat0u35k1p6w1/1/0.m4s (23 live) TLS GZIP 0ms 458 bytes (pipelined: 26)
May 07, 2019 16:56:49.916 [0x14d0cc895700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:50.001 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:50.001 [0x14d08cce5700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /:/timeline?ratingKey=134539&key=%2Flibrary%2Fmetadata%2F134539&playbackTime=0&playQueueItemID=868332&state=stopped&hasMDE=1&time=0&duration=1434000 (23 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:50.002 [0x14d08cce5700] DEBUG - Client [364mfnveg0c1e4tbnubuc2mc] reporting timeline state stopped, progress of 0/1434000ms for guid=, ratingKey=134539 url=, key=/library/metadata/134539, containerKey=, metadataId=134539, source=
May 07, 2019 16:56:50.003 [0x14d08cce5700] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 07, 2019 16:56:50.003 [0x14d08cce5700] DEBUG - Selecting best audio stream for part ID 322292 (autoselect: 0 language: en)
May 07, 2019 16:56:50.003 [0x14d08cce5700] DEBUG - We're going to try to auto-select a subtitle.
May 07, 2019 16:56:50.003 [0x14d08cce5700] DEBUG - Selected a foreign audio track: jpn
May 07, 2019 16:56:50.003 [0x14d08cce5700] DEBUG - Subtitles: Found a candidate subtitle language [eng] for a foreign film
May 07, 2019 16:56:50.003 [0x14d08cce5700] DEBUG - Audio Stream: 679860, Subtitle Stream: 679861
May 07, 2019 16:56:50.003 [0x14d08cce5700] DEBUG - [Now] User is chaberman43 (ID: 1)
May 07, 2019 16:56:50.004 [0x14d08cce5700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:56:50.004 [0x14d08cce5700] DEBUG - [Now] Profile is Web
May 07, 2019 16:56:50.004 [0x14d08cce5700] DEBUG - [Now] Updated play state for /library/metadata/134539.
May 07, 2019 16:56:50.004 [0x14d08cce5700] DEBUG - HubCache: Expiring '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeFeaturedTags=1' because of event plex.event.play.session.state.update.
May 07, 2019 16:56:50.004 [0x14d08cce5700] DEBUG - Statistics: (eqj26ba30ptj4dzb7op1p6yy) Reporting active playback in state 3 of type 4 (scrobble: 0) for account 1
May 07, 2019 16:56:50.006 [0x14d08cce5700] DEBUG - Streaming Resource: Removing session 0x14d05c3c97a0:364mfnveg0c1e4tbnubuc2mc
May 07, 2019 16:56:50.007 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 200 GET /:/timeline?ratingKey=134539&key=%2Flibrary%2Fmetadata%2F134539&playbackTime=0&playQueueItemID=868332&state=stopped&hasMDE=1&time=0&duration=1434000 (23 live) TLS GZIP 6ms 626 bytes (pipelined: 27)
May 07, 2019 16:56:50.010 [0x14d08e9f4700] DEBUG - Request: [127.0.0.1:59460 (Loopback)] GET /status/sessions (24 live) Signed-in Token (chaberman43)
May 07, 2019 16:56:50.010 [0x14d08e9f4700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:50.010 [0x14d0ce555700] DEBUG - Completed: [127.0.0.1:59460] 200 GET /status/sessions (24 live) 0ms 6433 bytes (pipelined: 1)
May 07, 2019 16:56:50.016 [0x14d08fbfd700] DEBUG - Request: [127.0.0.1:59460 (Loopback)] GET /status/sessions (24 live) Signed-in Token (chaberman43)
May 07, 2019 16:56:50.016 [0x14d08fbfd700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:50.017 [0x14d0ce756700] DEBUG - Completed: [127.0.0.1:59460] 200 GET /status/sessions (24 live) 0ms 6433 bytes (pipelined: 2)
May 07, 2019 16:56:50.036 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:50.036 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:50.036 [0x14d08c8e3700] DEBUG - Request: [73.9.170.41:45993 (WAN)] GET /status/sessions (24 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:50.036 [0x14d08e5f2700] DEBUG - Request: [73.9.170.41:25293 (WAN)] GET /status/sessions (24 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:50.036 [0x14d08c8e3700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:50.037 [0x14d08e5f2700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:50.037 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:45993] 200 GET /status/sessions (24 live) TLS GZIP 1ms 2699 bytes (pipelined: 5)
May 07, 2019 16:56:50.038 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:25293] 200 GET /status/sessions (24 live) TLS GZIP 2ms 2791 bytes (pipelined: 9)
May 07, 2019 16:56:50.055 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:50.056 [0x14d08d7eb700] DEBUG - Request: [73.9.170.41:42722 (WAN)] GET /status/sessions (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:50.056 [0x14d08d7eb700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:50.057 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:42722] 200 GET /status/sessions (25 live) TLS GZIP 1ms 2791 bytes (pipelined: 9)
May 07, 2019 16:56:50.065 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:50.065 [0x14d08d2e8700] DEBUG - Request: [192.168.20.248:44364 (Allowed Network (Subnet))] GET /status/sessions (25 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:56:50.066 [0x14d08d2e8700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:50.067 [0x14d0ce756700] DEBUG - Completed: [192.168.20.248:44364] 200 GET /status/sessions (25 live) TLS 1ms 6433 bytes (pipelined: 1)
May 07, 2019 16:56:51.919 [0x14d0cc694700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:52.623 [0x14d0ce555700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:56:52.625 [0x14d08e1f0700] DEBUG - Request: [66.186.97.26:6346 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (25 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:56:52.627 [0x14d08e1f0700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:56:52.633 [0x14d08e1f0700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:56:52.633 [0x14d08e1f0700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:56:52.633 [0x14d08e1f0700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:56:52.633 [0x14d08e1f0700] DEBUG - [Now] Profile is Web
May 07, 2019 16:56:52.633 [0x14d08e1f0700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:56:52.633 [0x14d08e1f0700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:56:52.634 [0x14d0cc895700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:52.636 [0x14d0ce555700] DEBUG - Completed: [66.186.97.26:6346] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (25 live) TLS GZIP 10ms 905 bytes (pipelined: 15)
May 07, 2019 16:56:52.656 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:52.656 [0x14d08fbfd700] DEBUG - Request: [192.168.20.248:44366 (Allowed Network (Subnet))] GET /status/sessions (26 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:56:52.656 [0x14d08fbfd700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:56:52.657 [0x14d0ce555700] DEBUG - Completed: [192.168.20.248:44366] 200 GET /status/sessions (26 live) TLS 1ms 6433 bytes (pipelined: 1)
May 07, 2019 16:56:53.103 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:53.103 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:53.103 [0x14d08e5f2700] DEBUG - Request: [73.9.170.41:25293 (WAN)] GET /accounts/1 (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:53.103 [0x14d08e9f4700] DEBUG - Request: [73.9.170.41:42722 (WAN)] GET /:/prefs (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:53.104 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:25293] 200 GET /accounts/1 (26 live) TLS GZIP 0ms 601 bytes (pipelined: 10)
May 07, 2019 16:56:53.107 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:42722] 200 GET /:/prefs (26 live) TLS GZIP 3ms 7401 bytes (pipelined: 10)
May 07, 2019 16:56:53.109 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:53.109 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:53.109 [0x14d08d7eb700] DEBUG - Request: [73.9.170.41:14639 (WAN)] GET /system/:/prefs (26 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:53.109 [0x14d08cee6700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /myplex/account (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:53.109 [0x14d08d7eb700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
May 07, 2019 16:56:53.109 [0x14d08d7eb700] DEBUG - HTTP requesting GET http://127.0.0.1:40167/system/:/prefs
May 07, 2019 16:56:53.109 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 200 GET /myplex/account (25 live) TLS GZIP 0ms 2317 bytes (pipelined: 28)
May 07, 2019 16:56:53.112 [0x14d08d7eb700] DEBUG - HTTP 304 response from GET http://127.0.0.1:40167/system/:/prefs
May 07, 2019 16:56:53.112 [0x14d08d7eb700] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
May 07, 2019 16:56:53.112 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:14639] 304 GET /system/:/prefs (25 live) TLS GZIP 3ms 403 bytes (pipelined: 12)
May 07, 2019 16:56:53.257 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:53.257 [0x14d08d2e8700] DEBUG - Request: [73.9.170.41:42722 (WAN)] PUT /myplex/refreshReachability (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:53.258 [0x14d08d2e8700] DEBUG - MyPlex: Requesting reachability check.
May 07, 2019 16:56:53.258 [0x14d08d2e8700] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=0d5f44f6-7305-47c7-9287-bceb7b6da2ab
May 07, 2019 16:56:53.265 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:53.265 [0x14d08e7f3700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /updater/status (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:53.266 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 200 GET /updater/status (25 live) TLS GZIP 0ms 519 bytes (pipelined: 29)
May 07, 2019 16:56:53.324 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:53.324 [0x14d08c6e2700] DEBUG - Request: [73.9.170.41:40702 (WAN)] PUT /updater/check?download=0 (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:53.324 [0x14d08c6e2700] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/5/check.xml?build=linux-x86_64&channel=8&distribution=debian&version=1.15.5.994-4610c6e8d, download: NO
May 07, 2019 16:56:53.324 [0x14d08c6e2700] DEBUG - Downloading document https://plex.tv/updater/products/5/check.xml?build=linux-x86_64&channel=8&distribution=debian&version=1.15.5.994-4610c6e8d
May 07, 2019 16:56:53.324 [0x14d08c6e2700] DEBUG - HTTP requesting GET https://plex.tv/updater/products/5/check.xml?build=linux-x86_64&channel=8&distribution=debian&version=1.15.5.994-4610c6e8d
May 07, 2019 16:56:53.727 [0x14d08d2e8700] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=0d5f44f6-7305-47c7-9287-bceb7b6da2ab
May 07, 2019 16:56:53.728 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:42722] 200 PUT /myplex/refreshReachability (25 live) TLS GZIP 470ms 274 bytes (pipelined: 11)
May 07, 2019 16:56:53.805 [0x14d08c6e2700] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/5/check.xml?build=linux-x86_64&channel=8&distribution=debian&version=1.15.5.994-4610c6e8d
May 07, 2019 16:56:53.806 [0x14d08c6e2700] INFO - AutoUpdate: no updates available
May 07, 2019 16:56:53.807 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:40702] 200 PUT /updater/check?download=0 (25 live) TLS GZIP 483ms 274 bytes (pipelined: 30)
May 07, 2019 16:56:53.920 [0x14d08ffff700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:54.085 [0x14d08e1f0700] DEBUG - Request: [54.171.49.143:55504 (WAN)] GET /identity (25 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:56:54.086 [0x14d0ce756700] DEBUG - Completed: [54.171.49.143:55504] 200 GET /identity (25 live) TLS 0ms 385 bytes (pipelined: 1)
May 07, 2019 16:56:54.477 [0x14d0ce555700] DEBUG - EventSource: Got event [data] '<Message address="162.218.150.137" port="32401" asyncIdentifier="0d5f44f6-7305-47c7-9287-bceb7b6da2ab" connectivity="1" command="notifyConnectivity"/>'
May 07, 2019 16:56:54.477 [0x14d0ce555700] DEBUG - PubSub: Got notified of reachability for async identifier 0d5f44f6-7305-47c7-9287-bceb7b6da2ab: 1 for 162.218.150.137:32401 (responded in 1 seconds)
May 07, 2019 16:56:54.508 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:54.508 [0x14d08fbfd700] DEBUG - Request: [73.9.170.41:45993 (WAN)] GET /myplex/account (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:54.509 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:45993] 200 GET /myplex/account (25 live) TLS GZIP 0ms 2238 bytes (pipelined: 6)
May 07, 2019 16:56:54.514 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:54.514 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:56:54.514 [0x14d08e9f4700] DEBUG - Request: [73.9.170.41:42722 (WAN)] GET /myplex/account (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:54.514 [0x14d08e5f2700] DEBUG - Request: [73.9.170.41:40702 (WAN)] GET /myplex/account (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:56:54.515 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:42722] 200 GET /myplex/account (25 live) TLS GZIP 0ms 2317 bytes (pipelined: 12)
May 07, 2019 16:56:54.515 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:40702] 200 GET /myplex/account (25 live) TLS GZIP 0ms 2317 bytes (pipelined: 31)
May 07, 2019 16:56:55.627 [0x14d08cee6700] DEBUG - Transcoder segment range: 209 - 1871 (1871)
May 07, 2019 16:56:55.922 [0x14d0cd29a700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:57.924 [0x14d08c2e0700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:56:59.925 [0x14d0cc292700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:01.927 [0x14d0cc694700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:02.628 [0x14d0ce555700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:57:02.630 [0x14d08d2e8700] DEBUG - Request: [66.186.97.26:6346 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (24 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:57:02.631 [0x14d08d2e8700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:57:02.637 [0x14d08d2e8700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:57:02.637 [0x14d08d2e8700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:57:02.637 [0x14d08d2e8700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:57:02.637 [0x14d08d2e8700] DEBUG - [Now] Profile is Web
May 07, 2019 16:57:02.637 [0x14d0ce555700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:57:02.637 [0x14d08d2e8700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:57:02.638 [0x14d08c6e2700] DEBUG - Request: [66.186.97.26:41989 (WAN)] GET /video/:/transcode/universal/ping?session=ohzr2upkzsf9khp2ab5zegjy (25 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:57:02.638 [0x14d08d2e8700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:57:02.638 [0x14d08c6e2700] DEBUG - Found session GUID of ohzr2upkzsf9khp2ab5zegjy in session start.
May 07, 2019 16:57:02.641 [0x14d08c6e2700] DEBUG - [Now] Freshening session based on ping for 4ozhkusgp2yg3jesnvmzg9sa_Video
May 07, 2019 16:57:02.641 [0x14d08c6e2700] DEBUG - Received transcode session ping for session ohzr2upkzsf9khp2ab5zegjy.
May 07, 2019 16:57:02.641 [0x14d0cd69c700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:02.641 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:41989] 200 GET /video/:/transcode/universal/ping?session=ohzr2upkzsf9khp2ab5zegjy (25 live) TLS GZIP 3ms 274 bytes (pipelined: 1)
May 07, 2019 16:57:02.642 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:6346] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (25 live) TLS GZIP 12ms 905 bytes (pipelined: 16)
May 07, 2019 16:57:02.661 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:02.661 [0x14d08c8e3700] DEBUG - Request: [192.168.20.248:44368 (Allowed Network (Subnet))] GET /status/sessions (26 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:57:02.661 [0x14d08c8e3700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:57:02.662 [0x14d0ce756700] DEBUG - Completed: [192.168.20.248:44368] 200 GET /status/sessions (26 live) TLS 1ms 6433 bytes (pipelined: 1)
May 07, 2019 16:57:03.928 [0x14d08fdfe700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:05.930 [0x14d0ce153700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:07.931 [0x14d0cc493700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:09.932 [0x14d0cd099700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:11.933 [0x14d08c2e0700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:12.246 [0x14d08cee6700] DEBUG - Transcoder segment range: 209 - 1872 (1872)
May 07, 2019 16:57:12.624 [0x14d0ce555700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:57:12.625 [0x14d08d2e8700] DEBUG - Request: [66.186.97.26:6346 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (25 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:57:12.625 [0x14d08d2e8700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:57:12.630 [0x14d08d2e8700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:57:12.630 [0x14d08d2e8700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:57:12.630 [0x14d08d2e8700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:57:12.630 [0x14d08d2e8700] DEBUG - [Now] Profile is Web
May 07, 2019 16:57:12.630 [0x14d08d2e8700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:57:12.630 [0x14d08d2e8700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:57:12.633 [0x14d08c2e0700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:12.635 [0x14d0ce555700] DEBUG - Completed: [66.186.97.26:6346] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (25 live) TLS GZIP 10ms 903 bytes (pipelined: 17)
May 07, 2019 16:57:12.653 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:12.653 [0x14d08d7eb700] DEBUG - Request: [192.168.20.248:44370 (Allowed Network (Subnet))] GET /status/sessions (26 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:57:12.654 [0x14d08d7eb700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:57:12.655 [0x14d0ce756700] DEBUG - Completed: [192.168.20.248:44370] 200 GET /status/sessions (26 live) TLS 1ms 6414 bytes (pipelined: 1)
May 07, 2019 16:57:13.936 [0x14d0cd49b700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:15.893 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:15.893 [0x14d08cce5700] DEBUG - Request: [192.168.20.248:44372 (Allowed Network (Subnet))] PUT /myplex/refreshReachability (26 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:57:15.894 [0x14d08cce5700] DEBUG - MyPlex: Requesting reachability check.
May 07, 2019 16:57:15.894 [0x14d08cce5700] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=2d31a715-c9f0-4de4-b2b1-f5221b72b8a4
May 07, 2019 16:57:15.937 [0x14d08d4e9700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:16.360 [0x14d08cce5700] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/aafb04cbaf3c88efa62cfdecf86b1c6bd9aa279a/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=2d31a715-c9f0-4de4-b2b1-f5221b72b8a4
May 07, 2019 16:57:16.361 [0x14d0ce555700] DEBUG - Completed: [192.168.20.248:44372] 200 PUT /myplex/refreshReachability (23 live) TLS 467ms 195 bytes (pipelined: 1)
May 07, 2019 16:57:16.423 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:16.424 [0x14d08cee6700] DEBUG - Request: [192.168.20.248:44374 (Allowed Network (Subnet))] GET /myplex/account (24 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:57:16.424 [0x14d0ce555700] DEBUG - Completed: [192.168.20.248:44374] 200 GET /myplex/account (24 live) TLS 1ms 3373 bytes (pipelined: 1)
May 07, 2019 16:57:16.695 [0x14d08d2e8700] DEBUG - Request: [63.34.171.72:46364 (WAN)] GET /identity (25 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:57:16.696 [0x14d0ce555700] DEBUG - Completed: [63.34.171.72:46364] 200 GET /identity (25 live) TLS 0ms 385 bytes (pipelined: 1)
May 07, 2019 16:57:16.976 [0x14d0ce555700] DEBUG - EventSource: Got event [data] '<Message address="162.218.150.137" port="32401" asyncIdentifier="2d31a715-c9f0-4de4-b2b1-f5221b72b8a4" connectivity="1" command="notifyConnectivity"/>'
May 07, 2019 16:57:16.976 [0x14d0ce555700] DEBUG - PubSub: Got notified of reachability for async identifier 2d31a715-c9f0-4de4-b2b1-f5221b72b8a4: 1 for 162.218.150.137:32401 (responded in 0 seconds)
May 07, 2019 16:57:17.078 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:17.078 [0x14d08d7eb700] DEBUG - Request: [73.9.170.41:57618 (WAN)] GET /myplex/account (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:57:17.080 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:57618] 200 GET /myplex/account (25 live) TLS GZIP 1ms 2238 bytes (pipelined: 1)
May 07, 2019 16:57:17.085 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:17.085 [0x14d08fbfd700] DEBUG - Request: [73.9.170.41:35621 (WAN)] GET /myplex/account (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:57:17.087 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:35621] 200 GET /myplex/account (25 live) TLS GZIP 1ms 2317 bytes (pipelined: 1)
May 07, 2019 16:57:17.090 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:17.090 [0x14d08e1f0700] DEBUG - Request: [73.9.170.41:13801 (WAN)] GET /myplex/account (25 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:57:17.092 [0x14d0ce756700] DEBUG - Completed: [73.9.170.41:13801] 200 GET /myplex/account (25 live) TLS GZIP 1ms 2317 bytes (pipelined: 1)
May 07, 2019 16:57:17.938 [0x14d0cd099700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:19.939 [0x14d0ce354700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:21.941 [0x14d08fdfe700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:22.624 [0x14d0ce756700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:57:22.625 [0x14d08e1f0700] DEBUG - Request: [66.186.97.26:6346 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (22 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:57:22.626 [0x14d08e1f0700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:57:22.631 [0x14d08e1f0700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:57:22.631 [0x14d08e1f0700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:57:22.631 [0x14d08e1f0700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:57:22.631 [0x14d08e1f0700] DEBUG - [Now] Profile is Web
May 07, 2019 16:57:22.631 [0x14d08e1f0700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:57:22.631 [0x14d08e1f0700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:57:22.639 [0x14d08d4e9700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:22.642 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:6346] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (23 live) TLS GZIP 17ms 904 bytes (pipelined: 18)
May 07, 2019 16:57:22.655 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:22.655 [0x14d08c8e3700] DEBUG - Request: [192.168.20.248:44376 (Allowed Network (Subnet))] GET /status/sessions (23 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:57:22.656 [0x14d08c8e3700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:57:22.658 [0x14d0ce555700] DEBUG - Completed: [192.168.20.248:44376] 200 GET /status/sessions (23 live) TLS 2ms 6432 bytes (pipelined: 1)
May 07, 2019 16:57:23.942 [0x14d0ce957700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:25.943 [0x14d0ce354700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:27.944 [0x14d0cd099700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:29.154 [0x14d08e7f3700] DEBUG - Transcoder segment range: 209 - 1873 (1873)
May 07, 2019 16:57:29.946 [0x14d0ce354700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:31.948 [0x14d0cc292700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:32.632 [0x14d0ce555700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:57:32.632 [0x14d08d7eb700] DEBUG - Request: [66.186.97.26:6346 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (22 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:57:32.633 [0x14d08d7eb700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:57:32.637 [0x14d08d7eb700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:57:32.637 [0x14d08d7eb700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:57:32.637 [0x14d08d7eb700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:57:32.637 [0x14d08d7eb700] DEBUG - [Now] Profile is Web
May 07, 2019 16:57:32.637 [0x14d08d7eb700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:57:32.637 [0x14d08d7eb700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:57:32.638 [0x14d08d4e9700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:32.640 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:6346] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (23 live) TLS GZIP 7ms 913 bytes (pipelined: 19)
May 07, 2019 16:57:32.642 [0x14d0ce756700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:57:32.642 [0x14d08fbfd700] DEBUG - Request: [66.186.97.26:47561 (WAN)] GET /video/:/transcode/universal/ping?session=ohzr2upkzsf9khp2ab5zegjy (23 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:57:32.642 [0x14d08fbfd700] DEBUG - Found session GUID of ohzr2upkzsf9khp2ab5zegjy in session start.
May 07, 2019 16:57:32.642 [0x14d08fbfd700] DEBUG - [Now] Freshening session based on ping for 4ozhkusgp2yg3jesnvmzg9sa_Video
May 07, 2019 16:57:32.642 [0x14d08fbfd700] DEBUG - Received transcode session ping for session ohzr2upkzsf9khp2ab5zegjy.
May 07, 2019 16:57:32.642 [0x14d0ce555700] DEBUG - Completed: [66.186.97.26:47561] 200 GET /video/:/transcode/universal/ping?session=ohzr2upkzsf9khp2ab5zegjy (23 live) TLS GZIP 0ms 274 bytes (pipelined: 1)
May 07, 2019 16:57:32.660 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:32.660 [0x14d08e5f2700] DEBUG - Request: [192.168.20.248:44378 (Allowed Network (Subnet))] GET /status/sessions (24 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:57:32.660 [0x14d08e5f2700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:57:32.661 [0x14d0ce555700] DEBUG - Completed: [192.168.20.248:44378] 200 GET /status/sessions (24 live) TLS 1ms 6446 bytes (pipelined: 1)
May 07, 2019 16:57:33.951 [0x14d0ce957700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:33.971 [0x14d0273f9700] DEBUG - BPQ: onConsiderProcessing: Idle (true)
May 07, 2019 16:57:33.971 [0x14d0273f9700] DEBUG - BPQ: [Idle] -> [Processing]
May 07, 2019 16:57:33.972 [0x14d0273f9700] DEBUG - BPQ: generating queue items from 0 generator(s)
May 07, 2019 16:57:33.972 [0x14d0273f9700] DEBUG - BPQ: generated 0 item(s) for queue
May 07, 2019 16:57:33.972 [0x14d0273f9700] DEBUG - PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
May 07, 2019 16:57:33.972 [0x14d0273f9700] DEBUG - PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
May 07, 2019 16:57:33.972 [0x14d0273f9700] DEBUG - BPQ: [Processing] -> [Idle]
May 07, 2019 16:57:35.951 [0x14d08c4e1700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:37.953 [0x14d08ffff700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:39.955 [0x14d0cc493700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:41.957 [0x14d0cc895700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:42.626 [0x14d0ce756700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:57:42.627 [0x14d08e1f0700] DEBUG - Request: [66.186.97.26:47561 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (18 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:57:42.627 [0x14d08e1f0700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:57:42.630 [0x14d08e1f0700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:57:42.630 [0x14d08e1f0700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:57:42.630 [0x14d08e1f0700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:57:42.630 [0x14d08e1f0700] DEBUG - [Now] Profile is Web
May 07, 2019 16:57:42.630 [0x14d08e1f0700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:57:42.631 [0x14d08e1f0700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:57:42.631 [0x14d0cc895700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:42.633 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:47561] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (18 live) TLS GZIP 5ms 913 bytes (pipelined: 2)
May 07, 2019 16:57:42.654 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:42.654 [0x14d08d7eb700] DEBUG - Request: [192.168.20.248:44380 (Allowed Network (Subnet))] GET /status/sessions (19 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:57:42.654 [0x14d08d7eb700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:57:42.656 [0x14d0ce756700] DEBUG - Completed: [192.168.20.248:44380] 200 GET /status/sessions (19 live) TLS 1ms 6445 bytes (pipelined: 1)
May 07, 2019 16:57:43.960 [0x14d0cd69c700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:45.561 [0x14d08cee6700] DEBUG - Transcoder segment range: 209 - 1874 (1874)
May 07, 2019 16:57:45.960 [0x14d08d4e9700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:47.963 [0x14d0cc292700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:49.964 [0x14d08cae4700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:51.965 [0x14d08fdfe700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:52.628 [0x14d0ce756700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:57:52.630 [0x14d08e1f0700] DEBUG - Request: [66.186.97.26:47561 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (18 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:57:52.632 [0x14d08e1f0700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:57:52.637 [0x14d08e1f0700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:57:52.637 [0x14d08e1f0700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:57:52.638 [0x14d08e1f0700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:57:52.638 [0x14d08e1f0700] DEBUG - [Now] Profile is Web
May 07, 2019 16:57:52.638 [0x14d08e1f0700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:57:52.638 [0x14d08e1f0700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:57:52.639 [0x14d0ce153700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:52.641 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:47561] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (18 live) TLS GZIP 11ms 910 bytes (pipelined: 3)
May 07, 2019 16:57:52.662 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:57:52.662 [0x14d08d7eb700] DEBUG - Request: [192.168.20.248:44386 (Allowed Network (Subnet))] GET /status/sessions (19 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:57:52.662 [0x14d08d7eb700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:57:52.663 [0x14d0ce756700] DEBUG - Completed: [192.168.20.248:44386] 200 GET /status/sessions (19 live) TLS 1ms 6447 bytes (pipelined: 1)
May 07, 2019 16:57:53.967 [0x14d0cd69c700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:55.969 [0x14d08cae4700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:57.970 [0x14d0cc292700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:57:59.971 [0x14d08cae4700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:58:01.972 [0x14d08c2e0700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:58:02.633 [0x14d0ce555700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:58:02.634 [0x14d08cee6700] DEBUG - Request: [66.186.97.26:47561 (WAN)] GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (17 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:58:02.634 [0x14d08cee6700] DEBUG - Client [4ozhkusgp2yg3jesnvmzg9sa] reporting timeline state paused, progress of 595000/7750000ms for guid=, ratingKey=118422 url=, key=/library/metadata/118422, containerKey=, metadataId=118422, source=
May 07, 2019 16:58:02.638 [0x14d08cee6700] DEBUG - Play progress on 118422 'To Kill a Mockingbird' - got played 595000 ms by account 2491064!
May 07, 2019 16:58:02.638 [0x14d08cee6700] DEBUG - [Now] User is airjrdn (ID: 2491064)
May 07, 2019 16:58:02.638 [0x14d08cee6700] DEBUG - [Now] Device is Chrome (Chrome).
May 07, 2019 16:58:02.638 [0x14d08cee6700] DEBUG - [Now] Profile is Web
May 07, 2019 16:58:02.638 [0x14d08cee6700] DEBUG - [Now] Updated play state for /library/metadata/118422.
May 07, 2019 16:58:02.639 [0x14d08cee6700] DEBUG - Statistics: (5klcap0d61wd76m6rgk4nenk) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 2491064
May 07, 2019 16:58:02.640 [0x14d08fdfe700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:58:02.642 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:47561] 200 GET /:/timeline?ratingKey=118422&key=%2Flibrary%2Fmetadata%2F118422&playbackTime=598978&playQueueItemID=867831&state=paused&hasMDE=1&time=595000&duration=7750000 (18 live) TLS GZIP 8ms 910 bytes (pipelined: 4)
May 07, 2019 16:58:02.643 [0x14d0ce756700] DEBUG - Auth: authenticated user 2491064 as airjrdn
May 07, 2019 16:58:02.644 [0x14d08e1f0700] DEBUG - Request: [66.186.97.26:36804 (WAN)] GET /video/:/transcode/universal/ping?session=ohzr2upkzsf9khp2ab5zegjy (18 live) TLS GZIP Signed-in Token (airjrdn)
May 07, 2019 16:58:02.644 [0x14d08e1f0700] DEBUG - Found session GUID of ohzr2upkzsf9khp2ab5zegjy in session start.
May 07, 2019 16:58:02.644 [0x14d08e1f0700] DEBUG - [Now] Freshening session based on ping for 4ozhkusgp2yg3jesnvmzg9sa_Video
May 07, 2019 16:58:02.644 [0x14d08e1f0700] DEBUG - Received transcode session ping for session ohzr2upkzsf9khp2ab5zegjy.
May 07, 2019 16:58:02.644 [0x14d0ce756700] DEBUG - Completed: [66.186.97.26:36804] 200 GET /video/:/transcode/universal/ping?session=ohzr2upkzsf9khp2ab5zegjy (18 live) TLS GZIP 0ms 274 bytes (pipelined: 1)
May 07, 2019 16:58:02.661 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:58:02.661 [0x14d08e9f4700] DEBUG - Request: [192.168.20.248:44388 (Allowed Network (Subnet))] GET /status/sessions (19 live) TLS Signed-in Token (chaberman43)
May 07, 2019 16:58:02.661 [0x14d08e9f4700] DEBUG - [Now] Adding 1 sessions.
May 07, 2019 16:58:02.663 [0x14d0ce756700] DEBUG - Completed: [192.168.20.248:44388] 200 GET /status/sessions (19 live) TLS 1ms 6446 bytes (pipelined: 1)
May 07, 2019 16:58:02.910 [0x14d08d7eb700] DEBUG - Transcoder segment range: 209 - 1875 (1875)
May 07, 2019 16:58:03.973 [0x14d08ffff700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:58:05.974 [0x14d08d4e9700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:58:07.811 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:58:07.811 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:58:07.811 [0x14d08e9f4700] DEBUG - Request: [73.9.170.41:48041 (WAN)] GET /accounts/1 (22 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:58:07.812 [0x14d08d7eb700] DEBUG - Request: [73.9.170.41:57728 (WAN)] GET /:/prefs (22 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:58:07.812 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:48041] 200 GET /accounts/1 (22 live) TLS GZIP 1ms 601 bytes (pipelined: 1)
May 07, 2019 16:58:07.816 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:57728] 200 GET /:/prefs (22 live) TLS GZIP 5ms 7401 bytes (pipelined: 1)
May 07, 2019 16:58:07.817 [0x14d0ce756700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:58:07.817 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:58:07.817 [0x14d08c6e2700] DEBUG - Request: [73.9.170.41:65479 (WAN)] GET /myplex/account (22 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:58:07.817 [0x14d08eff7700] DEBUG - Request: [73.9.170.41:38225 (WAN)] GET /system/:/prefs (22 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:58:07.817 [0x14d08eff7700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
May 07, 2019 16:58:07.817 [0x14d08eff7700] DEBUG - HTTP requesting GET http://127.0.0.1:40167/system/:/prefs
May 07, 2019 16:58:07.818 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:65479] 200 GET /myplex/account (22 live) TLS GZIP 0ms 2317 bytes (pipelined: 1)
May 07, 2019 16:58:07.821 [0x14d08eff7700] DEBUG - HTTP 304 response from GET http://127.0.0.1:40167/system/:/prefs
May 07, 2019 16:58:07.821 [0x14d08eff7700] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
May 07, 2019 16:58:07.821 [0x14d0ce555700] DEBUG - Completed: [73.9.170.41:38225] 304 GET /system/:/prefs (22 live) TLS GZIP 4ms 403 bytes (pipelined: 1)
May 07, 2019 16:58:07.975 [0x14d0cd69c700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:58:09.976 [0x14d0cc895700] DEBUG - Streaming Resource: Estimated bandwidth for 4ozhkusgp2yg3jesnvmzg9sa to be 0kbps over 20.00s
May 07, 2019 16:58:10.010 [0x14d0ce555700] DEBUG - Auth: authenticated user 1 as chaberman43
May 07, 2019 16:58:10.011 [0x14d08fbfd700] DEBUG - Request: [73.9.170.41:27670 (WAN)] GET /diagnostics/logs (23 live) TLS GZIP Signed-in Token (chaberman43)
May 07, 2019 16:58:10.011 [0x14d08fbfd700] DEBUG - Diagnostics: Building logfile zip
