no, i mean 10:27 of course … and i just mentioned it happened at 9:24 aswell
hardware encoding is activated and does its job as you can see in the screenshot attached.
as i said already… those errors happens aswell if there is no video transcoding …
Would you please try with my file?
I don’t have your file to compare against.
I’m still trying to determine if the root problem is a player problem or server problem.
do u have the file in 1080p instead of 4K?
Do you not have HEVC HDR capability with your GPU?
Which model is it?
i do its a Quadro P4000 the reason why i am asking is that i block transcoding 4k content
Go here.
Get the 110 Mbps H264 file.
I also have these.
not the easiest job to reproduce this sporadically error with an video of 30secs … but i will try
Do you have mkvtoolnix?
You can use it to make the file as long as you wish by using the Add/Append feature.
i could successfully recreate the error this morning:
Mar 21, 2022 08:18:35.029 [0x7f41caf4eb38] DEBUG - Request: [* . * . * . * :51680 (WAN)] GET /video/:/transcode/universal/session/jw5qqybzs3li9nqesqhwamn9/0/2.m4s (22 live) TLS GZIP Signed-in
Mar 21, 2022 08:18:35.029 [0x7f41caf4eb38] DEBUG - [Transcode/jw5qqybzs3li9nqesqhwamn9] Asked for segment 2 from session.
Mar 21, 2022 08:18:35.029 [0x7f41caf4eb38] DEBUG - [Transcode/jw5qqybzs3li9nqesqhwamn9] Returning segment 2 from session
Mar 21, 2022 08:18:35.029 [0x7f41caf4eb38] DEBUG - Content-Length of /transcode/Transcode/Sessions/plex-transcode-jw5qqybzs3li9nqesqhwamn9-76ced6c3-cc1b-4b72-9734-509719ef8eaa/init-stream0.m4s,/transcode/Transcode/Sessions/plex-transcode-jw5qqybzs3li9nqesqhwamn9-76ced6c3-cc1b-4b72-9734-509719ef8eaa/chunk-stream0-00003.m4s is 34297706 (of total: 34297706).
Mar 21, 2022 08:18:35.099 [0x7f41cbe1bb38] DEBUG - Request: [* . * . * . * :51690 (WAN)] GET /:/timeline?ratingKey=622057&key=%2Flibrary%2Fmetadata%2F622057&playbackTime=561&playQueueItemID=273452&state=playing&hasMDE=1&context=library%3Acontent.library&row=0&col=1&time=0&duration=1501000 (22 live) TLS GZIP Signed-in Token (okcool-test) (Opera)
Mar 21, 2022 08:18:35.099 [0x7f41cbe1bb38] DEBUG - Client [txepvdeus6ddrauqgy4mbl8p] reporting timeline state playing, progress of 0/1501000ms for guid=, playbackTime=561ms ratingKey=622057 url=, key=/library/metadata/622057, containerKey=, metadataId=622057, source=
Mar 21, 2022 08:18:35.101 [0x7f41cbe1bb38] DEBUG - [Now] User is okcool-test (ID: 1)
Mar 21, 2022 08:18:35.101 [0x7f41cbe1bb38] DEBUG - [Now] Device is Opera (Opera).
Mar 21, 2022 08:18:35.101 [0x7f41cbe1bb38] DEBUG - [Now] Profile is Web
Mar 21, 2022 08:18:35.101 [0x7f41cbe1bb38] DEBUG - [Now] Updated play state for /library/metadata/622057.
Mar 21, 2022 08:18:35.101 [0x7f41cbe1bb38] DEBUG - Statistics: (4wntmapkd69inuocorrt14oj) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Mar 21, 2022 08:18:35.102 [0x7f41cedf8b38] DEBUG - Completed: [* . * . * . * :51690] 200 GET /:/timeline?ratingKey=622057&key=%2Flibrary%2Fmetadata%2F622057&playbackTime=561&playQueueItemID=273452&state=playing&hasMDE=1&context=library%3Acontent.library&row=0&col=1&time=0&duration=1501000 (22 live) TLS GZIP 3ms 855 bytes (pipelined: 18)
Mar 21, 2022 08:18:35.199 [0x7f41cb077b38] DEBUG - Request: [10.0.2.4:40850 (Subnet)] GET /status/sessions (23 live) Signed-in Token (okcool-test) (acc4b19acc57)
Mar 21, 2022 08:18:35.199 [0x7f41cb077b38] DEBUG - [Now] Adding 1 sessions.
Mar 21, 2022 08:18:35.199 [0x7f41cee1bb38] DEBUG - Completed: [10.0.2.4:40850] 200 GET /status/sessions (23 live) 0ms 2690 bytes (pipelined: 1)
Mar 21, 2022 08:18:35.243 [0x7f41caf2bb38] DEBUG - Request: [10.0.2.4:40852 (Subnet)] GET /library/metadata/622057 (23 live) Signed-in Token (okcool-test) (acc4b19acc57)
Mar 21, 2022 08:18:35.247 [0x7f41cee1bb38] DEBUG - Completed: [10.0.2.4:40852] 200 GET /library/metadata/622057 (23 live) 3ms 1876 bytes (pipelined: 1)
Mar 21, 2022 08:18:35.564 [0x7f41caf4eb38] DEBUG - [Transcoder] [AVIOContext @ 0x7fb902584940] Statistics: 0 seeks, 118 writeouts
Mar 21, 2022 08:18:35.569 [0x7f41cb166b38] ERROR - [Transcoder] [mp4 @ 0x7fb90256fd00] failed to rename file chunk-stream0-00008.m4s.tmp to chunk-stream0-00008.m4s: No such file or directory
Mar 21, 2022 08:18:35.569 [0x7f41cbe1bb38] ERROR - [Transcoder] av_interleaved_write_frame(): No such file or directory
Mar 21, 2022 08:18:35.569 [0x7f41cb077b38] DEBUG - [Transcoder] No more output streams to write to, finishing.
Mar 21, 2022 08:18:35.569 [0x7f41caf2bb38] INFO - [Transcoder] [dash @ 0x7fb90256f040] Opening 'http://127.0.0.1:32400/video/:/transcode/session/jw5qqybzs3li9nqesqhwamn9/76ced6c3-cc1b-4b72-9734-509719ef8eaa/manifest?X-Plex-Http-Pipeline=infinite' for writing
Mar 21, 2022 08:18:35.570 [0x7f41cb034b38] DEBUG - [Transcoder] [tcp @ 0x7fb90254f280] Starting connection attempt to 127.0.0.1 port 32400
Mar 21, 2022 08:18:35.570 [0x7f41c9423b38] DEBUG - [Transcoder] [tcp @ 0x7fb90254f280] Successfully connected to 127.0.0.1 port 32400
Mar 21, 2022 08:18:35.570 [0x7f41cb166b38] DEBUG - [Transcoder] [AVIOContext @ 0x7fb902584c00] Statistics: 0 seeks, 1 writeouts
Mar 21, 2022 08:18:35.571 [0x7f41cbe1bb38] DEBUG - [Transcode] Transcoder segment range: 0 - 6 (6)
Mar 21, 2022 08:18:35.571 [0x7f41cbe1bb38] ERROR - [Transcode] Failed to calculate available disk space for path '"/transcode/Transcode/Sessions/plex-transcode-jw5qqybzs3li9nqesqhwamn9-76ced6c3-cc1b-4b72-9734-509719ef8eaa"': 2 (No such file or directory)
Mar 21, 2022 08:18:35.571 [0x7f41caf2bb38] INFO - [Transcoder] frame= 1220 fps=262 q=-1.0 Lsize=N/A time=00:00:35.80 bitrate=N/A speed= 7.7x
Mar 21, 2022 08:18:35.571 [0x7f41cb1b1b38] INFO - [Transcoder] video:252909kB audio:0kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: unknown
Mar 21, 2022 08:18:35.571 [0x7f41bdb0fb38] DEBUG - [Transcoder] Input file #0 (/mnt/okc-ger-movies/temp/test 15min 50mbps.mkv):
Mar 21, 2022 08:18:35.571 [0x7f41bd989b38] DEBUG - [Transcoder] Input stream #0:0 (video): 1220 packets read (258978401 bytes);
Mar 21, 2022 08:18:35.571 [0x7f41c9446b38] DEBUG - [Transcoder] Total: 1220 packets (258978401 bytes) demuxed
Mar 21, 2022 08:18:35.572 [0x7f41c9400b38] DEBUG - [Transcoder] Output file #0 (dash):
Mar 21, 2022 08:18:35.572 [0x7f41cb034b38] DEBUG - [Transcoder] Output stream #0:0 (video): 1220 packets muxed (258978401 bytes);
Mar 21, 2022 08:18:35.572 [0x7f41c9423b38] DEBUG - [Transcoder] Total: 1220 packets (258978401 bytes) muxed
Mar 21, 2022 08:18:35.572 [0x7f41cb166b38] DEBUG - [Transcoder] [AVIOContext @ 0x7fb90357ea00] Statistics: 261121844 bytes read, 5 seeks
Mar 21, 2022 08:18:35.572 [0x7f41cbe1bb38] INFO - [Transcoder] Conversion failed!
Mar 21, 2022 08:18:35.573 [0x7f41d0df3b38] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 7257 is 1 (failure)
Mar 21, 2022 08:18:35.573 [0x7f41cbb35b38] DEBUG - Streaming Resource: Terminating session 0x7f41cbd85ad8:txepvdeus6ddrauqgy4mbl8p which is using 104790kbps of WAN bandwidth. Used is now 0kbps
Mar 21, 2022 08:18:35.573 [0x7f41cb5aeb38] DEBUG - Killing job.
Mar 21, 2022 08:18:35.573 [0x7f41cb5aeb38] DEBUG - Signalling job ID 7257 with 9
Mar 21, 2022 08:18:35.573 [0x7f41cb5aeb38] DEBUG - Job was already killed, not killing again.
Mar 21, 2022 08:18:35.573 [0x7f41cb5aeb38] DEBUG - Stopping transcode session jw5qqybzs3li9nqesqhwamn9
Mar 21, 2022 08:18:35.573 [0x7f41cb898b38] DEBUG - Cleaning directory for session jw5qqybzs3li9nqesqhwamn9 (/transcode/Transcode/Sessions/plex-transcode-jw5qqybzs3li9nqesqhwamn9-76ced6c3-cc1b-4b72-9734-509719ef8eaa)
Mar 21, 2022 08:18:35.573 [0x7f41cbb35b38] DEBUG - Streaming Resource: Terminated session 0x7f41cbd85ad8:txepvdeus6ddrauqgy4mbl8p with reason Conversion failed. The transcoder exited due to an error.
Mar 21, 2022 08:18:35.579 [0x7f41cb5aeb38] DEBUG - Transcoder: Cleaning old transcode directories.
Mar 21, 2022 08:18:35.579 [0x7f41cb5aeb38] DEBUG - Whacked session jw5qqybzs3li9nqesqhwamn9, 0 remaining.
Mar 21, 2022 08:18:35.593 [0x7f41cb077b38] DEBUG - Request: [* . * . * . * :51690 (WAN)] GET /status/sessions (21 live) TLS GZIP Signed-in Token (okcool-test) (Opera)
Mar 21, 2022 08:18:35.593 [0x7f41cb077b38] DEBUG - [Now] Adding 1 sessions.
Mar 21, 2022 08:18:35.594 [0x7f41cedf8b38] DEBUG - Completed: [* . * . * . * :51690] 200 GET /status/sessions (21 live) TLS GZIP 0ms 1544 bytes (pipelined: 19)
Mar 21, 2022 08:18:35.644 [0x7f41cee1bb38] ERROR - Session 0x7f41cbd85ad8 terminated
plese see the full log attached
Plex Media Server Logs_2022-03-21_08-23-30.zip (5.2 MB)
Thank you for the logs.
- Where is
/transcodemapped? Is it on the network or local file system? - Observe below where the failure occurs.
a. It has been transcoding along, having delivered output blocks 1 - 7.
b. When it gets to the 8th block, there is no file.
Mar 21, 2022 08:18:35.199 [0x7f41cb077b38] DEBUG - Request: [10.0.2.4:40850 (Subnet)] GET /status/sessions (23 live) Signed-in Token (okcool-test) (acc4b19acc57)
Mar 21, 2022 08:18:35.199 [0x7f41cb077b38] DEBUG - [Now] Adding 1 sessions.
Mar 21, 2022 08:18:35.199 [0x7f41cee1bb38] DEBUG - Completed: [10.0.2.4:40850] 200 GET /status/sessions (23 live) 0ms 2690 bytes (pipelined: 1)
Mar 21, 2022 08:18:35.243 [0x7f41caf2bb38] DEBUG - Request: [10.0.2.4:40852 (Subnet)] GET /library/metadata/622057 (23 live) Signed-in Token (okcool-test) (acc4b19acc57)
Mar 21, 2022 08:18:35.247 [0x7f41cee1bb38] DEBUG - Completed: [10.0.2.4:40852] 200 GET /library/metadata/622057 (23 live) 3ms 1876 bytes (pipelined: 1)
Mar 21, 2022 08:18:35.564 [0x7f41caf4eb38] DEBUG - [Transcoder] [AVIOContext @ 0x7fb902584940] Statistics: 0 seeks, 118 writeouts
Mar 21, 2022 08:18:35.569 [0x7f41cb166b38] ERROR - [Transcoder] [mp4 @ 0x7fb90256fd00] failed to rename file chunk-stream0-00008.m4s.tmp to chunk-stream0-00008.m4s: No such file or directory
Mar 21, 2022 08:18:35.569 [0x7f41cbe1bb38] ERROR - [Transcoder] av_interleaved_write_frame(): No such file or directory
Mar 21, 2022 08:18:35.569 [0x7f41cb077b38] DEBUG - [Transcoder] No more output streams to write to, finishing.
Mar 21, 2022 08:18:35.569 [0x7f41caf2bb38] INFO - [Transcoder] [dash @ 0x7fb90256f040] Opening 'http://127.0.0.1:32400/video/:/transcode/session/jw5qqybzs3li9nqesqhwamn9/76ced6c3-cc1b-4b72-9734-509719ef8eaa/manifest?X-Plex-Http-Pipeline=infinite' for writing
Mar 21, 2022 08:18:35.570 [0x7f41cb034b38] DEBUG - [Transcoder] [tcp @ 0x7fb90254f280] Starting connection attempt to 127.0.0.1 port 32400
Mar 21, 2022 08:18:35.570 [0x7f41c9423b38] DEBUG - [Transcoder] [tcp @ 0x7fb90254f280] Successfully connected to 127.0.0.1 port 32400
Mar 21, 2022 08:18:35.570 [0x7f41cb166b38] DEBUG - [Transcoder] [AVIOContext @ 0x7fb902584c00] Statistics: 0 seeks, 1 writeouts
Mar 21, 2022 08:18:35.571 [0x7f41cbe1bb38] DEBUG - [Transcode] Transcoder segment range: 0 - 6 (6)
Mar 21, 2022 08:18:35.571 [0x7f41cbe1bb38] ERROR - [Transcode] Failed to calculate available disk space for path '"/transcode/Transcode/Sessions/plex-transcode-jw5qqybzs3li9nqesqhwamn9-76ced6c3-cc1b-4b72-9734-509719ef8eaa"': 2 (No such file or directory)
Mar 21, 2022 08:18:35.571 [0x7f41caf2bb38] INFO - [Transcoder] frame= 1220 fps=262 q=-1.0 Lsize=N/A time=00:00:35.80 bitrate=N/A speed= 7.7x
Mar 21, 2022 08:18:35.571 [0x7f41cb1b1b38] INFO - [Transcoder] video:252909kB audio:0kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: unknown
Mar 21, 2022 08:18:35.571 [0x7f41bdb0fb38] DEBUG - [Transcoder] Input file #0 (/mnt/okc-ger-movies/temp/test 15min 50mbps.mkv):
Mar 21, 2022 08:18:35.571 [0x7f41bd989b38] DEBUG - [Transcoder] Input stream #0:0 (video): 1220 packets read (258978401 bytes);
Mar 21, 2022 08:18:35.571 [0x7f41c9446b38] DEBUG - [Transcoder] Total: 1220 packets (258978401 bytes) demuxed
Mar 21, 2022 08:18:35.572 [0x7f41c9400b38] DEBUG - [Transcoder] Output file #0 (dash):
Mar 21, 2022 08:18:35.572 [0x7f41cb034b38] DEBUG - [Transcoder] Output stream #0:0 (video): 1220 packets muxed (258978401 bytes);
Mar 21, 2022 08:18:35.572 [0x7f41c9423b38] DEBUG - [Transcoder] Total: 1220 packets (258978401 bytes) muxed
Mar 21, 2022 08:18:35.572 [0x7f41cb166b38] DEBUG - [Transcoder] [AVIOContext @ 0x7fb90357ea00] Statistics: 261121844 bytes read, 5 seeks
Mar 21, 2022 08:18:35.572 [0x7f41cbe1bb38] INFO - [Transcoder] Conversion failed!
Mar 21, 2022 08:18:35.573 [0x7f41d0df3b38] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 7257 is 1 (failure)
Mar 21, 2022 08:18:35.573 [0x7f41cbb35b38] DEBUG - Streaming Resource: Terminating session 0x7f41cbd85ad8:txepvdeus6ddrauqgy4mbl8p which is using 104790kbps of WAN bandwidth. Used is now 0kbps
Mar 21, 2022 08:18:35.573 [0x7f41cb5aeb38] DEBUG - Killing job.
Where did it go?
Failed to calculate available disk space for path '"/transcode/Transcode/Sessions/plex-transcode-jw5qqybzs3li9nqesqhwamn9-76ced6c3-cc1b-4b72-9734-509719ef8eaa"': 2 (No such file or directory)
failed to rename file chunk-stream0-00008.m4s.tmp to chunk-stream0-00008.m4s: No such file or directory
-
/transcode is mapped to a local filesystem
-
thats a good questions… it seems like plex is “loosing” the folder… the thing is not all transcodes get the same error at the same time … what i am trying to say the mapped folder is always available…
I should have also asked
Is the /transcode directory mapped to a USB drive or an internal HDD?
My thinking is:
a. drive faulted or went to sleep and didn’t wake up in time.
b. drive was unmounted for other reasons
c. something is mucking with permissions for the /transcode map (external)
its mapped to an internal nvme
a and b. i guess thats not possible since not all transcodes get interrupted …
c. how can i check that?
How difficult would it be to shutdown the server,
unmount the drive,
run fsck on it (make sure no FS corruption)
and then run a smartctl test ?
Something isn’t right there.
i just started to transcode to ram … its still happening so it cannot be an issue with my nvme
This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.
