Server Version#: v1.25.2.5319-c43dc0277
OS: Ubuntu 20.04.3 LTS (Focal Fossa)
Running Plex on Proxmox in an LXC container and having PMS randomly crash. I’ve attempted on two different hypervisors and having the same issue on both. Issue is PMS server/service is randomly crashing and restarting. I can go back in when the service is back up and resume watching the movie, but about 15-20 minutes later it would crash again. Nothing stands out in the logs to me. This is the first time I have had this issue with PMS setup in this manner for about a year now.
I was able to grab the logs at the time it crashed the final time.
Jan 03, 2022 23:23:36.187 [0x7efcee010b38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=-51.0&size=-22&remaining=8487&speed=1.2 (44 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:23:36.187 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=-51.0&size=-22&remaining=8487&speed=1.2 (44 live) 0ms 355 bytes (pipelined: 198) (range: bytes=0-)
Jan 03, 2022 23:23:36.553 [0x7efceec27b38] DEBUG - Completed: [192.168.1.247:51217] 200 GET /video/:/transcode/universal/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/base/00439.ts (43 live) TLS 20993ms 62858224 bytes (pipelined: 7)
Jan 03, 2022 23:23:36.553 [0x7efceec27b38] DEBUG - Removed transcode data consumer, active count 2 => 1
Jan 03, 2022 23:23:36.871 [0x7efceec27b38] DEBUG - Request: didn't get any data from 192.168.1.1:39627: Connection reset by peer
Jan 03, 2022 23:23:36.908 [0x7efcee010b38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=-51.0&size=-22&remaining=8482&speed=1.7 (44 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:23:36.908 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=-51.0&size=-22&remaining=8482&speed=1.7 (44 live) 0ms 355 bytes (pipelined: 199) (range: bytes=0-)
Jan 03, 2022 23:36:00.042 [0x7efcee010b38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:00.042 [0x7efceec04b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (32 live) 0ms 355 bytes (pipelined: 1497) (range: bytes=0-)
Jan 03, 2022 23:36:00.540 [0x7efceec27b38] DEBUG - Request: didn't get any data from 192.168.1.1:57852: Connection reset by peer
Jan 03, 2022 23:36:00.755 [0x7efcee19ab38] DEBUG - Request: [192.168.1.247:58945 (Subnet)] GET /:/timeline?bufferedTime=27&col=0&context=source%3Ahub.movie.inprogress&duration=7969984&key=%2Flibrary%2Fmetadata%2F6856&location=lan&playQueueItemID=1392&playbackTime=845233&ratingKey=6856&row=0&state=playing&time=5116486 (32 live) TLS GZIP Signed-in Token (Redacted)
Jan 03, 2022 23:36:00.756 [0x7efcee19ab38] DEBUG - Client [86DBE274-2E01-481C-A532-F326536CF000] reporting timeline state playing, progress of 5116486/7969984ms for guid=, playbackTime=845233ms ratingKey=6856 url=, key=/library/metadata/6856, containerKey=, metadataId=6856, source=
Jan 03, 2022 23:36:00.756 [0x7efcee19ab38] DEBUG - Play progress on 6856 'The Magnificent Seven' - got played 5116486 ms by account 1!
Jan 03, 2022 23:36:00.757 [0x7efcee19ab38] DEBUG - [Now] User is Redacted (ID: 1)
Jan 03, 2022 23:36:00.757 [0x7efcee19ab38] DEBUG - [Now] Device is tvOS (Living Room).
Jan 03, 2022 23:36:00.757 [0x7efcee19ab38] DEBUG - [Now] Profile is tvOS
Jan 03, 2022 23:36:00.757 [0x7efcee19ab38] DEBUG - [Now] Updated play state for /library/metadata/6856.
Jan 03, 2022 23:36:00.757 [0x7efcee19ab38] DEBUG - Statistics: (86DBE274-2E01-481C-A532-F326536CF000) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jan 03, 2022 23:36:00.758 [0x7efceec27b38] DEBUG - Completed: [192.168.1.247:58945] 200 GET /:/timeline?bufferedTime=27&col=0&context=source%3Ahub.movie.inprogress&duration=7969984&key=%2Flibrary%2Fmetadata%2F6856&location=lan&playQueueItemID=1392&playbackTime=845233&ratingKey=6856&row=0&state=playing&time=5116486 (32 live) TLS GZIP 3ms 787 bytes (pipelined: 220)
Jan 03, 2022 23:36:00.762 [0x7efcedfcfb38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:00.762 [0x7efceec04b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (32 live) 0ms 355 bytes (pipelined: 1498) (range: bytes=0-)
Jan 03, 2022 23:36:01.482 [0x7efcee8aab38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (31 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:01.482 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (31 live) 0ms 355 bytes (pipelined: 1499) (range: bytes=0-)
Jan 03, 2022 23:36:01.550 [0x7efceec27b38] DEBUG - Request: didn't get any data from 192.168.1.1:57876: Connection reset by peer
Jan 03, 2022 23:36:02.202 [0x7efcee8aab38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:02.203 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (32 live) 0ms 355 bytes (pipelined: 1500) (range: bytes=0-)
Jan 03, 2022 23:36:02.574 [0x7efceec04b38] DEBUG - Request: didn't get any data from 192.168.1.1:57900: Connection reset by peer
Jan 03, 2022 23:36:02.922 [0x7efcee010b38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:02.922 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (32 live) 0ms 355 bytes (pipelined: 1501) (range: bytes=0-)
Jan 03, 2022 23:36:03.294 [0x7efcedfcfb38] DEBUG - Request: [127.0.0.1:51766 (Loopback)] POST /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/manifest (33 live) Signed-in
Jan 03, 2022 23:36:03.294 [0x7efcee19ab38] DEBUG - Request: [127.0.0.1:51764 (Loopback)] POST /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/manifest (33 live) Signed-in
Jan 03, 2022 23:36:03.295 [0x7efcee19ab38] DEBUG - [Transcode] Transcoder segment range: 427 - 527 (527)
Jan 03, 2022 23:36:03.295 [0x7efcedfcfb38] DEBUG - [Transcode] Transcoder segment range: 427 - 528 (527)
Jan 03, 2022 23:36:03.295 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51764] 200 POST /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/manifest (33 live) 0ms 166 bytes
Jan 03, 2022 23:36:03.295 [0x7efceec04b38] DEBUG - Completed: [127.0.0.1:51766] 200 POST /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/manifest (33 live) 0ms 166 bytes
Jan 03, 2022 23:36:03.591 [0x7efceec04b38] DEBUG - Request: didn't get any data from 192.168.1.1:57925: Connection reset by peer
Jan 03, 2022 23:36:03.643 [0x7efcee010b38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:03.643 [0x7efcee010b38] DEBUG - [Transcode] Session 3091E63A-4104-4A4D-9784-5E8CB80AAB3C (3) is throttling
Jan 03, 2022 23:36:03.643 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1&speed=0.0 (32 live) 0ms 371 bytes (pipelined: 1502) (range: bytes=0-)
Jan 03, 2022 23:36:03.643 [0x7efcee8aab38] DEBUG - [Transcoder] Throttle - Going into sloth mode.
Jan 03, 2022 23:36:04.245 [0x7efcee867b38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:04.245 [0x7efceec04b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1 (32 live) 0ms 371 bytes (pipelined: 1504) (range: bytes=0-)
Jan 03, 2022 23:36:04.288 [0x7efcee19ab38] DEBUG - Request: [192.168.1.247:58945 (Subnet)] GET /video/:/transcode/universal/ping?audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&fastSeek=1&includeCodecs=1&includeKeyframePlaylist=1&location=lan&mediaBufferSize=40000&partIndex=0&path=%2Flibrary%2Fmetadata%2F6856&protocol=hls&session=3091E63A-4104-4A4D-9784-5E8CB80AAB3C&subtitleSize=100&videoQuality=100&videoResolution=4096x2160 (31 live) TLS GZIP Signed-in Token (Redacted)
Jan 03, 2022 23:36:04.288 [0x7efcee19ab38] DEBUG - [Transcode] [Now] Freshening session based on ping for 86DBE274-2E01-481C-A532-F326536CF000_Video
Jan 03, 2022 23:36:04.288 [0x7efcee19ab38] DEBUG - [Transcode] Received transcode session ping for session 3091E63A-4104-4A4D-9784-5E8CB80AAB3C.
Jan 03, 2022 23:36:04.289 [0x7efceec04b38] DEBUG - Completed: [192.168.1.247:58945] 200 GET /video/:/transcode/universal/ping?audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&fastSeek=1&includeCodecs=1&includeKeyframePlaylist=1&location=lan&mediaBufferSize=40000&partIndex=0&path=%2Flibrary%2Fmetadata%2F6856&protocol=hls&session=3091E63A-4104-4A4D-9784-5E8CB80AAB3C&subtitleSize=100&videoQuality=100&videoResolution=4096x2160 (31 live) TLS GZIP 0ms 195 bytes (pipelined: 221)
Jan 03, 2022 23:36:04.591 [0x7efceec27b38] DEBUG - Request: didn't get any data from 192.168.1.1:57949: Connection reset by peer
Jan 03, 2022 23:36:04.746 [0x7efcee867b38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:04.746 [0x7efceec04b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1 (32 live) 0ms 371 bytes (pipelined: 1505) (range: bytes=0-)
Jan 03, 2022 23:36:05.095 [0x7efceec04b38] DEBUG - Completed: [192.168.1.247:51218] 200 GET /video/:/transcode/universal/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/base/00514.ts (32 live) TLS 17819ms 49335520 bytes (pipelined: 44)
Jan 03, 2022 23:36:05.095 [0x7efceec04b38] DEBUG - Removed transcode data consumer, active count 2 => 1
Jan 03, 2022 23:36:05.247 [0x7efcee19ab38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:05.248 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1 (32 live) 0ms 371 bytes (pipelined: 1506) (range: bytes=0-)
Jan 03, 2022 23:36:05.592 [0x7efceec04b38] DEBUG - Request: didn't get any data from 192.168.1.1:57975: Connection reset by peer
Jan 03, 2022 23:36:05.749 [0x7efcee19ab38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:05.749 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1 (32 live) 0ms 371 bytes (pipelined: 1507) (range: bytes=0-)
Jan 03, 2022 23:36:05.941 [0x7efcedfcfb38] DEBUG - Request: [192.168.1.247:58945 (Subnet)] GET /:/timeline?bufferedTime=28&col=0&context=source%3Ahub.movie.inprogress&duration=7969984&key=%2Flibrary%2Fmetadata%2F6856&location=lan&playQueueItemID=1392&playbackTime=850444&ratingKey=6856&row=0&state=playing&time=5121700 (32 live) TLS GZIP Signed-in Token (Redacted)
Jan 03, 2022 23:36:05.941 [0x7efcedfcfb38] DEBUG - Client [86DBE274-2E01-481C-A532-F326536CF000] reporting timeline state playing, progress of 5121700/7969984ms for guid=, playbackTime=850444ms ratingKey=6856 url=, key=/library/metadata/6856, containerKey=, metadataId=6856, source=
Jan 03, 2022 23:36:05.942 [0x7efcedfcfb38] DEBUG - Play progress on 6856 'The Magnificent Seven' - got played 5121700 ms by account 1!
Jan 03, 2022 23:36:05.942 [0x7efcedfcfb38] DEBUG - [Now] User is Redacted (ID: 1)
Jan 03, 2022 23:36:05.942 [0x7efcedfcfb38] DEBUG - [Now] Device is tvOS (Living Room).
Jan 03, 2022 23:36:05.942 [0x7efcedfcfb38] DEBUG - [Now] Profile is tvOS
Jan 03, 2022 23:36:05.942 [0x7efcedfcfb38] DEBUG - [Now] Updated play state for /library/metadata/6856.
Jan 03, 2022 23:36:05.942 [0x7efcedfcfb38] DEBUG - Statistics: (86DBE274-2E01-481C-A532-F326536CF000) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jan 03, 2022 23:36:05.943 [0x7efceec04b38] DEBUG - Completed: [192.168.1.247:58945] 200 GET /:/timeline?bufferedTime=28&col=0&context=source%3Ahub.movie.inprogress&duration=7969984&key=%2Flibrary%2Fmetadata%2F6856&location=lan&playQueueItemID=1392&playbackTime=850444&ratingKey=6856&row=0&state=playing&time=5121700 (32 live) TLS GZIP 2ms 788 bytes (pipelined: 222)
Jan 03, 2022 23:36:05.963 [0x7efcee010b38] DEBUG - Request: [192.168.1.247:51218 (Subnet)] GET /video/:/transcode/universal/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/base/00516.ts (32 live) TLS Signed-in
Jan 03, 2022 23:36:05.963 [0x7efcee010b38] DEBUG - [Transcode/3091E63A-4104-4A4D-9784-5E8CB80AAB3C] Asked for segment 516 from session.
Jan 03, 2022 23:36:05.963 [0x7efcee010b38] DEBUG - [Transcode/3091E63A-4104-4A4D-9784-5E8CB80AAB3C] Returning segment 516 from session
Jan 03, 2022 23:36:05.963 [0x7efcee010b38] DEBUG - Content-Length of /dev/shm/Transcode/Sessions/plex-transcode-3091E63A-4104-4A4D-9784-5E8CB80AAB3C-1e5e6687-27fa-4716-8877-6213da875a74/media-00516.ts is 45112906 (of total: 45112906).
Jan 03, 2022 23:36:06.250 [0x7efcee8aab38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:06.250 [0x7efcee8aab38] DEBUG - [Transcode] Session 3091E63A-4104-4A4D-9784-5E8CB80AAB3C (3) is unthrottling
Jan 03, 2022 23:36:06.250 [0x7efceec04b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=7.9&size=-22&remaining=-1 (32 live) 0ms 355 bytes (pipelined: 1508) (range: bytes=0-)
Jan 03, 2022 23:36:06.250 [0x7efcee867b38] DEBUG - [Transcoder] Throttle - Getting back to work.
Jan 03, 2022 23:36:06.631 [0x7efceec27b38] DEBUG - Request: didn't get any data from 192.168.1.1:57999: Connection reset by peer
Jan 03, 2022 23:36:07.169 [0x7efcee8aab38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=-1&speed=373.9 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:07.169 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=-1&speed=373.9 (32 live) 0ms 355 bytes (pipelined: 1510) (range: bytes=0-)
Jan 03, 2022 23:36:07.644 [0x7efceec27b38] DEBUG - Request: didn't get any data from 192.168.1.1:58023: Connection reset by peer
Jan 03, 2022 23:36:07.911 [0x7efcedfcfb38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=-1&speed=0.0 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:07.911 [0x7efceec04b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=-1&speed=0.0 (32 live) 0ms 355 bytes (pipelined: 1511) (range: bytes=0-)
Jan 03, 2022 23:36:08.266 [0x7efceec27b38] DEBUG - Completed: [192.168.1.197:63442] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (32 live) TLS GZIP 20000ms 5 bytes (pipelined: 49)
Jan 03, 2022 23:36:08.337 [0x7efcee19ab38] DEBUG - Request: [192.168.1.197:63442 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (31 live) TLS GZIP Signed-in Token (Redacted)
Jan 03, 2022 23:36:08.337 [0x7efcee19ab38] DEBUG - Content-Length is -1 (of total: -1).
Jan 03, 2022 23:36:08.650 [0x7efcedfcfb38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=-1&speed=0.0 (31 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:08.650 [0x7efceec04b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=-1&speed=0.0 (31 live) 0ms 355 bytes (pipelined: 1512) (range: bytes=0-)
Jan 03, 2022 23:36:08.654 [0x7efceec04b38] DEBUG - Request: didn't get any data from 192.168.1.1:58048: Connection reset by peer
Jan 03, 2022 23:36:09.372 [0x7efcedfcfb38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=-1&speed=4.9 (31 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:09.372 [0x7efceec04b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=-1&speed=4.9 (31 live) 0ms 355 bytes (pipelined: 1513) (range: bytes=0-)
Jan 03, 2022 23:36:09.674 [0x7efceec27b38] DEBUG - Request: didn't get any data from 192.168.1.1:58072: Connection reset by peer
Jan 03, 2022 23:36:10.117 [0x7efcee8aab38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=11021&speed=0.3 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:10.118 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=11021&speed=0.3 (32 live) 0ms 355 bytes (pipelined: 1514) (range: bytes=0-)
Jan 03, 2022 23:36:10.685 [0x7efceec04b38] DEBUG - Request: didn't get any data from 192.168.1.1:58096: Connection reset by peer
Jan 03, 2022 23:36:10.833 [0x7efcee010b38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=12796&speed=1.4 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:10.834 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=12796&speed=1.4 (32 live) 1ms 355 bytes (pipelined: 1515) (range: bytes=0-)
Jan 03, 2022 23:36:11.253 [0x7efcee19ab38] DEBUG - Request: [192.168.1.247:58945 (Subnet)] GET /:/timeline?bufferedTime=30&col=0&context=source%3Ahub.movie.inprogress&duration=7969984&key=%2Flibrary%2Fmetadata%2F6856&location=lan&playQueueItemID=1392&playbackTime=855733&ratingKey=6856&row=0&state=playing&time=5126997 (32 live) TLS GZIP Signed-in Token (Redacted)
Jan 03, 2022 23:36:11.263 [0x7efcee19ab38] DEBUG - Client [86DBE274-2E01-481C-A532-F326536CF000] reporting timeline state playing, progress of 5126997/7969984ms for guid=, playbackTime=855733ms ratingKey=6856 url=, key=/library/metadata/6856, containerKey=, metadataId=6856, source=
Jan 03, 2022 23:36:11.278 [0x7efcee19ab38] DEBUG - Play progress on 6856 'The Magnificent Seven' - got played 5126997 ms by account 1!
Jan 03, 2022 23:36:11.281 [0x7efcee19ab38] DEBUG - [Now] User is Redacted (ID: 1)
Jan 03, 2022 23:36:11.287 [0x7efcee19ab38] DEBUG - [Now] Device is tvOS (Living Room).
Jan 03, 2022 23:36:11.287 [0x7efcee19ab38] DEBUG - [Now] Profile is tvOS
Jan 03, 2022 23:36:11.287 [0x7efcee19ab38] DEBUG - [Now] Updated play state for /library/metadata/6856.
Jan 03, 2022 23:36:11.288 [0x7efcee19ab38] DEBUG - Statistics: (86DBE274-2E01-481C-A532-F326536CF000) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jan 03, 2022 23:36:11.338 [0x7efceec04b38] DEBUG - Completed: [192.168.1.247:58945] 200 GET /:/timeline?bufferedTime=30&col=0&context=source%3Ahub.movie.inprogress&duration=7969984&key=%2Flibrary%2Fmetadata%2F6856&location=lan&playQueueItemID=1392&playbackTime=855733&ratingKey=6856&row=0&state=playing&time=5126997 (32 live) TLS GZIP 85ms 808 bytes (pipelined: 223)
Jan 03, 2022 23:36:11.698 [0x7efceec27b38] DEBUG - Request: didn't get any data from 192.168.1.1:58120: Connection reset by peer
Jan 03, 2022 23:36:11.914 [0x7efcee8aab38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=24552&speed=0.2 (32 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:11.914 [0x7efceec27b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=24552&speed=0.2 (32 live) 0ms 355 bytes (pipelined: 1516) (range: bytes=0-)
Jan 03, 2022 23:36:12.612 [0x7efcee010b38] DEBUG - Request: [127.0.0.1:51334 (Loopback)] PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=-1&speed=0.0 (31 live) Signed-in Token (Redacted) (range: bytes=0-)
Jan 03, 2022 23:36:12.613 [0x7efceec04b38] DEBUG - Completed: [127.0.0.1:51334] 206 PUT /video/:/transcode/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/1e5e6687-27fa-4716-8877-6213da875a74/progress?progress=12.7&size=-22&remaining=-1&speed=0.0 (31 live) 0ms 355 bytes (pipelined: 1517) (range: bytes=0-)
Jan 03, 2022 23:36:12.724 [0x7efceec27b38] DEBUG - Request: didn't get any data from 192.168.1.1:58145: Connection reset by peer
Jan 03, 2022 23:36:12.789 [0x7efcf0355b38] WARN - JobManager: Could not find job for handle 455
Jan 03, 2022 23:36:12.962 [0x7efceec27b38] DEBUG - Completed: [192.168.1.247:51217] 200 GET /video/:/transcode/universal/session/3091E63A-4104-4A4D-9784-5E8CB80AAB3C/base/00515.ts (32 live) TLS 15496ms 45827245 bytes (pipelined: 45)
Jan 03, 2022 23:36:12.962 [0x7efceec27b38] DEBUG - Removed transcode data consumer, active count 2 => 1
Jan 03, 2022 23:36:13.089 [0x7efcf0355b38] DEBUG - Shutting down with signal 15 (Terminated)
Jan 03, 2022 23:36:13.089 [0x7efcf0355b38] DEBUG - Ordered to stop server.
Jan 03, 2022 23:36:13.092 [0x7efcf3b88b58] DEBUG - Stopping server...
Jan 03, 2022 23:36:13.092 [0x7efcf3b88b58] DEBUG - HttpServer: Stopping server.
Jan 03, 2022 23:36:13.095 [0x7efcee053b38] DEBUG - MetadataItemClusterRequestHandler: a shutdown event has been received
Jan 03, 2022 23:36:13.095 [0x7efcee053b38] DEBUG - TranscodeJobManager: [Running] onServerShutdown
Jan 03, 2022 23:36:13.095 [0x7efcee053b38] DEBUG - TranscodeJobManager: [Running] -> [Shutdown]
Jan 03, 2022 23:36:13.096 [0x7efcf3b88b58] DEBUG - Server stopped.
Jan 03, 2022 23:36:13.140 [0x7efcf3b88b58] DEBUG - NetworkService: Stopping advertisement.
Jan 03, 2022 23:36:13.140 [0x7efcee11ab38] ERROR - Network Service: Error in advertiser handle read: 125 (Operation canceled) socket=-1
Jan 03, 2022 23:36:13.140 [0x7efcf3b88b58] DEBUG - Killing job.
Jan 03, 2022 23:36:13.140 [0x7efcf3b88b58] DEBUG - Signalling job ID 630 with 9
Jan 03, 2022 23:36:13.140 [0x7efcf3b88b58] DEBUG - Job was already killed, not killing again.
Jan 03, 2022 23:36:13.140 [0x7efcf3b88b58] DEBUG - Stopping transcode session 3091E63A-4104-4A4D-9784-5E8CB80AAB3C
Jan 03, 2022 23:36:13.142 [0x7efcf0355b38] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 630 is -9 (signal: Killed)
Jan 03, 2022 23:36:13.142 [0x7efced83ab38] DEBUG - Notifying consumer that data is complete.
Jan 03, 2022 23:36:13.145 [0x7efcf3b88b58] DEBUG - Transcoder: Cleaning old transcode directories.
Jan 03, 2022 23:36:13.146 [0x7efcf3b88b58] DEBUG - Transcoder: Cleaning old transcode directory: "/dev/shm/Transcode/Sessions/plex-transcode-3091E63A-4104-4A4D-9784-5E8CB80AAB3C-1e5e6687-27fa-4716-8877-6213da875a74"
Jan 03, 2022 23:36:13.179 [0x7efcf0355b38] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Tuner Service' exit code for process 406 is 0 (success)
Jan 03, 2022 23:36:13.179 [0x7efcee076b38] DEBUG - [ChildProcessMonitor] Plex Tuner Service exited.
Jan 03, 2022 23:36:13.179 [0x7efcee076b38] DEBUG - [ChildProcessMonitor] attempting to relaunch Plex Tuner Service; attempt number 1, 2 tries remaining
Jan 03, 2022 23:36:13.240 [0x7efcee11ab38] INFO - Network Service: Abandoning advertise socket, it was closed.
Jan 03, 2022 23:36:13.487 [0x7efcf3b88b58] DEBUG - Whacked session 3091E63A-4104-4A4D-9784-5E8CB80AAB3C, 0 remaining.
Jan 03, 2022 23:36:13.487 [0x7efcf3b88b58] DEBUG - Killing plug-ins.
Jan 03, 2022 23:36:13.504 [0x7efcf3b88b58] DEBUG - [com.plexapp.agents.imdb] Killing.
Jan 03, 2022 23:36:13.506 [0x7efcf0355b38] WARN - JobManager: Could not find job for handle 533
Jan 03, 2022 23:36:13.510 [0x7efcf3b88b58] DEBUG - [com.plexapp.system] Killing.
Jan 03, 2022 23:36:13.511 [0x7efcf0355b38] WARN - JobManager: Could not find job for handle 367
Jan 03, 2022 23:36:13.512 [0x7efcf3b88b58] DEBUG - [com.plexapp.agents.subzero] Killing.
Jan 03, 2022 23:36:13.513 [0x7efcf3b88b58] DEBUG - ChildProcessMonitor: Waiting for Plex Tuner Service to exit.
Jan 03, 2022 23:36:13.513 [0x7efcf3b88b58] DEBUG - [JobRunner] Signalling job ID 406 with 2
Jan 03, 2022 23:36:18.196 [0x7efcee758b38] DEBUG - Stopping file watcher for com.plexapp.system
Jan 03, 2022 23:36:19.351 [0x7efced7aeb38] DEBUG - Stopping file watcher for com.plexapp.agents.subzero
Jan 03, 2022 23:36:23.113 [0x7efcee16fb38] DEBUG - Stopping file watcher for com.plexapp.agents.imdb
Jan 03, 2022 23:36:32.293 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.588119 seconds: 192.168.1.117 (192.168.1.117 - Sonos One SL)
Jan 03, 2022 23:36:32.293 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.588205 seconds: 192.168.1.117 (Living Room - Sonos One SL Media Renderer)
Jan 03, 2022 23:36:32.293 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.733947 seconds: 192.168.1.149 (192.168.1.149 - Sonos Arc SL)
Jan 03, 2022 23:36:32.293 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.733952 seconds: 192.168.1.149 (192.168.1.149 - Sonos Arc SL Media Server)
Jan 03, 2022 23:36:32.293 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.733956 seconds: 192.168.1.149 (Living Room - Sonos Arc SL Media Renderer)
Jan 03, 2022 23:36:32.293 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.044124 seconds: 192.168.1.181 (SHIELD)
Jan 03, 2022 23:36:32.293 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 20.690567 seconds: 192.168.1.187 (Philips hue (192.168.1.187))
Jan 03, 2022 23:36:32.293 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 20.295889 seconds: 192.168.1.194 (Living Room Display)
Jan 03, 2022 23:36:32.294 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 20.773496 seconds: 192.168.1.214 ([LG] webOS TV OLED65B8PUA)
Jan 03, 2022 23:36:32.294 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.859475 seconds: 192.168.1.223 (192.168.1.223 - Sonos One SL)
Jan 03, 2022 23:36:32.294 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.859478 seconds: 192.168.1.223 (Living Room - Sonos One SL Media Renderer)
Jan 03, 2022 23:36:32.294 [0x7efcee8cdb38] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 20.291955 seconds: 192.168.1.233 (Master Bedroom Display)
Jan 03, 2022 23:36:32.780 [0x7efcf3b88b58] INFO - Killing process: Plex EAE Service (pid: 613)
Jan 03, 2022 23:36:32.781 [0x7efcf3b88b58] DEBUG - Killing job.
Jan 03, 2022 23:36:32.781 [0x7efcf3b88b58] DEBUG - Signalling job ID 613 with 9
Jan 03, 2022 23:36:32.781 [0x7efcf3b88b58] DEBUG - Database: Shutting down.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 0.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 1.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 2.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 3.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 4.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 5.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 6.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 7.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 8.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 9.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 10.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 11.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 12.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 13.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 14.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 15.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 16.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 17.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 18.
Jan 03, 2022 23:36:32.782 [0x7efcf3b88b58] DEBUG - Captured session 19.
Jan 03, 2022 23:36:32.795 [0x7efcf0355b38] DEBUG - Jobs: '/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Codecs/EasyAudioEncoder-1625-linux-x86_64/EasyAudioEncoder/EasyAudioEncoder' exit code for process 613 is -9 (signal: Killed)
Jan 03, 2022 23:36:33.386 [0x7efcf3b88b58] DEBUG - Database: Shutting down.
Jan 03, 2022 23:36:33.386 [0x7efcf3b88b58] DEBUG - Captured session 0.
Jan 03, 2022 23:36:33.386 [0x7efcf3b88b58] DEBUG - Captured session 1.
