Server Version#: 1.30.2.6563-3d4dc0cce
PlexMediaServer install: PlexMediaServer-1.30.2.6563-3d4dc0cce - Installation starting.
PlexMediaServer install:
PlexMediaServer install: Now installing based on:
PlexMediaServer install: Installation Type: Update
PlexMediaServer install: Process Control: systemd
PlexMediaServer install: Plex User: plex
PlexMediaServer install: Plex Group: plex
PlexMediaServer install: Video Group: render
PlexMediaServer install: Metadata Dir: /var/lib/plexmediaserver/Library/Application Support
PlexMediaServer install: Temp Directory: /mnt/plex_temp_dir/transcode (set in Preferences.xml)
PlexMediaServer install: Lang Encoding: en_US.UTF-8
PlexMediaServer install: Processor: Intel(R) Xeon(R) CPU E5-2697 v4 @ 2.30GHz
PlexMediaServer install: Intel i915 Hardware: Not found
PlexMediaServer install: Nvidia GPU card: Not Found
PlexMediaServer install:
PlexMediaServer install: Completing final configuration.
PlexMediaServer install: Starting Plex Media Server.
PlexMediaServer install: PlexMediaServer-1.30.2.6563-3d4dc0cce - Installation successful. Errors: 0, Warnings: 0
Hello,
I can’t seem to transcode anything and store it offline on a device. Transcoding will always start but than fail less then 30 seconds after starting. In my scenario I have an Android phone with plex installed and I am triggering the download. The plex server is hosted on a 5.15.0-58-generic #64-Ubuntu system.
The log doesn’t provide a lot of context around why it failed other than the process was sigkilled. Is there perhaps a way to change the Jjob running command line error level to debug? I’ve switched debug on within plex it self.
In the logs the job will always show the following:
Jan 26, 2023 04:41:35.118 [0x7f14b63acb38] DEBUG - [Req#9c/Transcode/JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/acf6c67-4446-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx4f64-ac06-f7e17b3e91c8 "/usr/lib/plexmediaserver/Plex Transcoder" "-codec:#0x1011" hevc "-codec:#0x1100" ac3 -analyzeduration 20000000 -probesize 20000000 -i "/media/storage/MOVIE.m2ts" -filter_complex "[0:#0x1011]scale=w=1280:h=720:force_divisible_by=4[0];[0]format=pix_fmts=yuv420p|nv12[1]" -map "[1]" -codec:0 libx264 -crf:0 23 -maxrate:0 3416k -bufsize:0 6832k -r:0 23.975999999999999 -preset:0 fast -level:0 5.2 -x264opts:0 subme=1:me_range=4:rc_lookahead=10:me=hex:8x8dct=0:partitions=none -filter_complex "[0:#0x1100] aresample=async=1:ochl='5.1':rematrix_maxval=0.000000dB:osr=48000[2]" -map "[2]" -codec:1 libopus -b:1 336k -f matroska -map_metadata -1 -map_chapters -1 Output.mkv -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress
Jan 26, 2023 04:41:35.118 [0x7f14b63acb38] DEBUG - [Req#9c/Transcode/JobRunner] In directory: "/mnt/plex_temp_dir/transcode/Transcode/Sessions/plex-transcode-d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527-a1d39ce1-c6a4-4f4e-81dc-282851a96574"
Jan 26, 2023 04:41:35.119 [0x7f14b63acb38] DEBUG - [Req#9c/Transcode/JobRunner] Jobs: Starting child process with pid 2936
Jan 26, 2023 04:41:35.127 [0x7f14bab5bb38] DEBUG - Request: [127.0.0.1:56730 (Loopback)] PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress?status=startup (18 live) #96 Signed-in Token (USER1) (range: bytes=0-)
Jan 26, 2023 04:41:35.128 [0x7f14bbb16b38] DEBUG - Completed: [127.0.0.1:56730] 204 PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress?status=startup (18 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-)
Jan 26, 2023 04:41:35.131 [0x7f14bab5bb38] DEBUG - Request: [127.0.0.1:56730 (Loopback)] PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress?status=startup (18 live) #a0 Signed-in Token (USER1) (range: bytes=0-)
Jan 26, 2023 04:41:35.131 [0x7f14bb913b38] DEBUG - Completed: [127.0.0.1:56730] 204 PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress?status=startup (18 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-)
Jan 26, 2023 04:41:35.131 [0x7f14bab5bb38] DEBUG - Request: [127.0.0.1:56730 (Loopback)] PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress?status=opening (18 live) #a1 Signed-in Token (USER1) (range: bytes=0-)
Jan 26, 2023 04:41:35.132 [0x7f14bb913b38] DEBUG - Completed: [127.0.0.1:56730] 204 PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress?status=opening (18 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-)
Jan 26, 2023 04:41:35.139 [0x7f14bab5bb38] DEBUG - Request: [127.0.0.1:56730 (Loopback)] PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress?status=opened (18 live) #a2 Signed-in Token (USER1) (range: bytes=0-)
Jan 26, 2023 04:41:35.139 [0x7f14bbb16b38] DEBUG - Completed: [127.0.0.1:56730] 204 PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress?status=opened (18 live) 0ms 203 bytes (pipelined: 4) (range: bytes=0-)
Jan 26, 2023 04:41:35.139 [0x7f14bab5bb38] DEBUG - Request: [127.0.0.1:56730 (Loopback)] PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress/stream?index=0&id=4113&codec=hevc&type=video (18 live) #a3 Signed-in Token (USER1) (range: bytes=0-)
Jan 26, 2023 04:41:35.140 [0x7f14bbb16b38] DEBUG - Completed: [127.0.0.1:56730] 200 PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress/stream?index=0&id=4113&codec=hevc&type=video (18 live) 0ms 195 bytes (pipelined: 5) (range: bytes=0-)
Jan 26, 2023 04:41:35.140 [0x7f14bab5bb38] DEBUG - Request: [127.0.0.1:56730 (Loopback)] PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress/stream?index=1&id=4352&codec=ac3&type=audio (18 live) #a4 Signed-in Token (USER1) (range: bytes=0-)
Jan 26, 2023 04:41:35.140 [0x7f14bbb16b38] DEBUG - Completed: [127.0.0.1:56730] 200 PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress/stream?index=1&id=4352&codec=ac3&type=audio (18 live) 0ms 195 bytes (pipelined: 6) (range: bytes=0-)
Jan 26, 2023 04:41:35.625 [0x7f14bab5bb38] DEBUG - Request: [127.0.0.1:56730 (Loopback)] PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress/streamDetail?index=0&id=4113&codec=hevc&type=video&profile=Main%2010&width=3840&height=2160&interlaced=0&sar=1:1&level=153&frameRate=23.976 (18 live) #a5 Signed-in Token (USER1) (range: bytes=0-)
Jan 26, 2023 04:41:35.625 [0x7f14bb913b38] DEBUG - Completed: [127.0.0.1:56730] 200 PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress/streamDetail?index=0&id=4113&codec=hevc&type=video&profile=Main%2010&width=3840&height=2160&interlaced=0&sar=1:1&level=153&frameRate=23.976 (18 live) 0ms 195 bytes (pipelined: 7) (range: bytes=0-)
Jan 26, 2023 04:41:35.626 [0x7f14bab5bb38] DEBUG - Request: [127.0.0.1:56730 (Loopback)] PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress/streamDetail?index=1&id=4352&codec=ac3&type=audio&bitrate=448000&channels=6&layout=5.1%28side%29&sampleRate=48000 (18 live) #a6 Signed-in Token (USER1) (range: bytes=0-)
Jan 26, 2023 04:41:35.626 [0x7f14bb913b38] DEBUG - Completed: [127.0.0.1:56730] 200 PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress/streamDetail?index=1&id=4352&codec=ac3&type=audio&bitrate=448000&channels=6&layout=5.1%28side%29&sampleRate=48000 (18 live) 0ms 195 bytes (pipelined: 8) (range: bytes=0-)
Jan 26, 2023 04:41:35.628 [0x7f14bab5bb38] DEBUG - Request: [127.0.0.1:56730 (Loopback)] PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress?duration=11102.178056 (18 live) #a7 Signed-in Token (USER1) (range: bytes=0-)
Jan 26, 2023 04:41:35.628 [0x7f14b63acb38] DEBUG - [Req#9c/Transcode] Using default transcode duration of 120 minutes
Jan 26, 2023 04:41:35.628 [0x7f14b63acb38] DEBUG - [Req#9c/Transcode] Started session successfully: d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527
Jan 26, 2023 04:41:35.628 [0x7f14bbb16b38] DEBUG - Completed: [127.0.0.1:56730] 204 PUT /video/:/transcode/session/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574/progress?duration=11102.178056 (18 live) 0ms 203 bytes (pipelined: 9) (range: bytes=0-)
Jan 26, 2023 04:41:35.629 [0x7f14b63acb38] DEBUG - [Req#9c/Transcode] Activity: registered new activity b74bb770-2ca2-4b3e-a116-c32c3ddc7fa6 - ""
Jan 26, 2023 04:41:35.629 [0x7f14b63acb38] DEBUG - [Req#9c/Transcode] Activity: updated activity b74bb770-2ca2-4b3e-a116-c32c3ddc7fa6 - completed -1.0% - Transcoding Media for USER1
Jan 26, 2023 04:41:35.630 [0x7f14b63acb38] DEBUG - [Req#9c/Transcode] Streaming Resource: Reached Decision id=44005 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=98910 part=(id=99062 decision=transcode container=mkv protocol=http streams=(Video=(id=231655 decision=transcode bitrate=3416 encoder=libx264 width=1280 height=720) Audio=(id=231656 decision=transcode bitrate=336 encoder=libopus channels=6 rate=48000))))
Jan 26, 2023 04:41:35.632 [0x7f14bbb16b38] DEBUG - Completed: [192.168.1.29:34688] 200 GET /video/:/transcode/universal/decision?audioBoost=100&autoAdjustQuality=1&context=static&directPlay=0&directStream=1&directStreamAudio=1&fastSeek=1&hasMDE=1&location=lan&maxVideoBitrate=4000&mediaBufferSize=74944&mediaIndex=0&offlineTranscode=1&partIndex=0&path=%2Flibrary%2Fmetadata%2F44005&protocol=*&session=d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527&subtitleSize=100&videoBitrate=4000&videoQuality=100&videoResolution=3840x2160 (18 live) TLS GZIP 530ms 4843 bytes (pipelined: 14)
Jan 26, 2023 04:41:36.422 [0x7f14b63acb38] DEBUG - [Req#a8/Transcode/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574] Streaming Resource: Unthrottling session 0x7f14b8d537b8:802e2691-679e-4904-beb8-f72cf79339d5 which is using static transcoder slot. Used slots is now 1
Jan 26, 2023 04:41:36.422 [0x7f14b63acb38] DEBUG - [Req#a8/Transcode/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574] Transcoder: session d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527 indicated fallback to software decoding
Jan 26, 2023 04:41:37.770 [0x7f14bab5bb38] DEBUG - Request: [192.168.1.29:34700 (Subnet)] GET /transcode/sessions/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527 (17 live) #c9 TLS GZIP Signed-in Token (USER1) (Pixel 7 Pro)
Jan 26, 2023 04:41:37.771 [0x7f14bb913b38] DEBUG - Completed: [192.168.1.29:34700] 200 GET /transcode/sessions/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527 (17 live) TLS GZIP 1ms 648 bytes (pipelined: 7)
Jan 26, 2023 04:41:40.227 [0x7f14bab5bb38] DEBUG - [Req#d7/Transcode/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574] Activity: updated activity b74bb770-2ca2-4b3e-a116-c32c3ddc7fa6 - completed 0.1% - Transcoding Media for USER1
Jan 26, 2023 04:41:45.271 [0x7f14bab5bb38] DEBUG - [Req#f2/Transcode/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574] Activity: updated activity b74bb770-2ca2-4b3e-a116-c32c3ddc7fa6 - completed 0.4% - Transcoding Media for USER1
Jan 26, 2023 04:41:47.787 [0x7f14bab5bb38] DEBUG - Request: [192.168.1.29:34700 (Subnet)] GET /transcode/sessions/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527 (18 live) #cb TLS GZIP Signed-in Token (USER1) (Pixel 7 Pro)
Jan 26, 2023 04:41:47.788 [0x7f14bb913b38] DEBUG - Completed: [192.168.1.29:34700] 200 GET /transcode/sessions/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527 (18 live) TLS GZIP 0ms 651 bytes (pipelined: 8)
Jan 26, 2023 04:41:50.304 [0x7f14bab5bb38] DEBUG - [Req#129/Transcode/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574] Activity: updated activity b74bb770-2ca2-4b3e-a116-c32c3ddc7fa6 - completed 0.6% - Transcoding Media for USER1
Jan 26, 2023 04:41:55.338 [0x7f14b63acb38] DEBUG - [Req#146/Transcode/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527/a1d39ce1-c6a4-4f4e-81dc-282851a96574] Activity: updated activity b74bb770-2ca2-4b3e-a116-c32c3ddc7fa6 - completed 0.8% - Transcoding Media for USER1
Jan 26, 2023 04:41:57.815 [0x7f14b63acb38] DEBUG - Request: [192.168.1.29:34700 (Subnet)] GET /transcode/sessions/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527 (14 live) #116 TLS GZIP Signed-in Token (USER1) (Pixel 7 Pro)
Jan 26, 2023 04:41:57.816 [0x7f14bbb16b38] DEBUG - Completed: [192.168.1.29:34700] 200 GET /transcode/sessions/d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527 (14 live) TLS GZIP 5ms 661 bytes (pipelined: 9)
Jan 26, 2023 04:41:59.536 [0x7f14bab5bb38] DEBUG - Request: [192.168.1.29:34700 (Subnet)] GET /video/:/transcode/universal/stop?session=d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527 (13 live) #15d TLS GZIP Signed-in Token (USER1) (Pixel 7 Pro)
Jan 26, 2023 04:41:59.536 [0x7f14b7806b38] DEBUG - [Req#15d/Transcode] Killing job.
Jan 26, 2023 04:41:59.536 [0x7f14b7806b38] DEBUG - [Req#15d/Transcode] Signalling job ID 2936 with 9
Jan 26, 2023 04:41:59.536 [0x7f14b7806b38] DEBUG - [Req#15d/Transcode] Job was already killed, not killing again.
Jan 26, 2023 04:41:59.536 [0x7f14b7806b38] DEBUG - [Req#15d/Transcode] Stopping transcode session d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527
Jan 26, 2023 04:41:59.536 [0x7f14bbb16b38] DEBUG - Completed: [192.168.1.29:34700] 200 GET /video/:/transcode/universal/stop?session=d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527 (13 live) TLS GZIP 0ms 195 bytes (pipelined: 10)
Jan 26, 2023 04:41:59.536 [0x7f14b7806b38] DEBUG - [Req#15d/Transcode] Activity: Ended activity b74bb770-2ca2-4b3e-a116-c32c3ddc7fa6.
Jan 26, 2023 04:41:59.538 [0x7f14b7806b38] DEBUG - [Req#15d/Transcode] Transcoder: Cleaning old transcode directories.
Jan 26, 2023 04:41:59.538 [0x7f14b7806b38] DEBUG - [Req#15d/Transcode] Transcoder: Cleaning old transcode directory: "/mnt/plex_temp_dir/transcode/Transcode/Sessions/plex-transcode-d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527-a1d39ce1-c6a4-4f4e-81dc-282851a96574"
Jan 26, 2023 04:41:59.538 [0x7f14b7806b38] DEBUG - [Req#15d/Transcode] Whacked session d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527, 0 remaining.
Jan 26, 2023 04:41:59.647 [0x7f14bbd50b38] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 2936 is -9 (signal: Killed)
Jan 26, 2023 04:41:59.648 [0x7f14b9b4ab38] DEBUG - Streaming Resource: Releasing session 0x7f14b8d537b8:802e2691-679e-4904-beb8-f72cf79339d5 which is using static transcoder slot. Used slots is now 0
Jan 26, 2023 04:41:59.648 [0x7f14b9b4ab38] DEBUG - Cleaning directory for session d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527 (/mnt/plex_temp_dir/transcode/Transcode/Sessions/plex-transcode-d42b290c5b3b34fc0fbb3ff28e584a0bbc0f0527-a1d39ce1-c6a4-4f4e-81dc-282851a96574)
Jan 26, 2023 04:41:59.699 [0x7f14bab5bb38] DEBUG - Request: [192.168.1.10:36344 (Subnet)] GET /video/:/transcode/universal/ping?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=209664&session=a170ea4a80c78944-com-plexapp-android (12 live) #16a TLS GZIP Signed-in Token (USER1) (SHIELD Android TV)
Jan 26, 2023 04:41:59.699 [0x7f14bab5bb38] WARN - [Req#16a/Transcode] Got a transcode session ping without a valid session ID.
