DS916+ transcoding issues

gregr · June 2, 2020, 10:51pm

I'm seeing transcoding issues on my DS916+ recently (not sure when exactly it started), where it's no longer able to transcode at 1x or faster. Seeing closer to 0.3-0.5x. Playing material recorded from HDHR Connect.

Seeing the following in the log (this includes a skip forward):

2020/06/02 16:44:05.497148 [ENC] Starting encoder for Late Night With Seth Meyers S07E107 Anna Kendrick Action Bronson 2020-06-01-2336.mpg in /volume1/media/DVR Recordings/Streaming/file2659-ip192.168.0.13-134218809/encoder-138-323381971 at 138 (157.960356) (encoder=h264_vaapi, resolution=1080, deinterlacer=blend, bitrate=10000 segment_size=0.01)
2020/06/02 16:44:05.512698 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x2ca16c0] Dropped corrupted packet (stream = 1)
2020/06/02 16:44:05.512755 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x2ca16c0] Dropped corrupted packet (stream = 2)
2020/06/02 16:44:05.513170 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x2ca16c0] stream 1 : no PTS found at end of file, duration not set
2020/06/02 16:44:05.513226 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x2ca16c0] stream 2 : no PTS found at end of file, duration not set
2020/06/02 16:44:05.530464 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x2ca16c0] Dropped corrupted packet (stream = 1)
2020/06/02 16:44:05.530537 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x2ca16c0] Dropped corrupted packet (stream = 2)
2020/06/02 16:44:20.345720 [ENC] Request for 164 is more than 10 from the last encoded segment of 144
2020/06/02 16:44:20.368562 [ENC] Stopped encoder for Late Night With Seth Meyers S07E107 Anna Kendrick Action Bronson 2020-06-01-2336.mpg in /volume1/media/DVR Recordings/Streaming/file2659-ip192.168.0.13-134218809/encoder-138-323381971 after encoding 138 to 144
2020/06/02 16:44:20.369552 [ENC] Starting encoder for Late Night With Seth Meyers S07E107 Anna Kendrick Action Bronson 2020-06-01-2336.mpg in /volume1/media/DVR Recordings/Streaming/file2659-ip192.168.0.13-134218809/encoder-164-231411734 at 164 (184.420122) (encoder=h264_vaapi, resolution=1080, deinterlacer=blend, bitrate=10000 segment_size=0.01)
2020/06/02 16:44:20.385239 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x380c6c0] Dropped corrupted packet (stream = 1)
2020/06/02 16:44:20.385311 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x380c6c0] Dropped corrupted packet (stream = 2)
2020/06/02 16:44:20.385723 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x380c6c0] stream 1 : no PTS found at end of file, duration not set
2020/06/02 16:44:20.385777 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x380c6c0] stream 2 : no PTS found at end of file, duration not set
2020/06/02 16:44:20.400621 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x380c6c0] Dropped corrupted packet (stream = 1)
2020/06/02 16:44:20.400671 [HLS] ffmpeg: file2659-ip192.168.0.13:  [mpegts @ 0x380c6c0] Dropped corrupted packet (stream = 2)
2020/06/02 16:44:32.988186 [ENC] Stopped encoder for Late Night With Seth Meyers S07E107 Anna Kendrick Action Bronson 2020-06-01-2336.mpg in /volume1/media/DVR Recordings/Streaming/file2659-ip192.168.0.13-134218809/encoder-164-231411734 after encoding 164 to 169

Settings are, I believe, on the defaults:

Transcoder: Hardware
Deinterlacer: Blend
Adaptive Streaming: On

Web player settings:

Server Quantity: 1080p @ 10mbps
Client Buffer: 30s

DVR version: 2020.05.25.2131

Tried both Chrome and Safari (both on MacOS) to see if it might be a client issue, same behavior on both. Client computer is wired to NAS over 1Gbps link.

Is there a setting I should change somewhere, perhaps?

Thanks!

tmm1 · June 2, 2020, 11:24pm

I think you need to change this to Hardware.

gregr · June 2, 2020, 11:40pm

Just tried that; same issue, transcoding at around 0.6x.

2020/06/02 17:38:43.321094 [TNR] Opened connection to 10776069/0 for ch4.1 KCNC-TV
2020/06/02 17:38:43.368420 [HLS] Starting transcoder for channel 4.1 from 192.168.0.13 (encoder=remux, resolution=, deinterlacer=, bitrate=0)
2020/06/02 17:38:44.800885 [HLS] Probed live stream in 1.432213042s: mpeg2video 1920x1080 tt 14030344bps
2020/06/02 17:38:46.203809 [HLS] Session ch4.1-dANY-ip192.168.0.13 started in 3.365342254s
2020/06/02 17:38:46.208936 [ENC] Starting encoder for ch4.1 in /volume1/media/DVR Recordings/Streaming/ch4.1-dANY-ip192.168.0.13-531533949/encoder-1-182289336 at 1 (1.127911) (encoder=h264_vaapi, resolution=1080, deinterlacer=hardware, bitrate=10000 segment_size=0.01)
2020/06/02 17:39:04.223698 [ENC] Segment 11 has unexpected duration: inputs=11 expected=0.934267 actual=1.001 expected_pts=11.805244-12.706144 actual_pts=11.826578-12.794211
2020/06/02 17:39:20.265729 [HLS] Stopping transcoder session ch4.1-dANY-ip192.168.0.13 (out: 36.429856s, finished: false)
2020/06/02 17:39:20.286738 [ENC] Stopped encoder for ch4.1 in /volume1/media/DVR Recordings/Streaming/ch4.1-dANY-ip192.168.0.13-531533949/encoder-1-182289336 after encoding 1 to 21
2020/06/02 17:39:20.286997 [TNR] Closed connection to 10776069/0 for ch4.1 KCNC-TV
2020/06/02 17:39:20.288105 [SNR] Statistics for ch4.1 KCNC-TV: ss=100% snq=100% seq=100% bps=13910997,14185728-16178528 pps=1321,1347-1536

tmm1 · June 3, 2020, 2:43am

Very strange. Have you tried rebooting the NAS? Does the activity monitor show a lot of CPU usage?

Any recent DSM updates?

djcastaldo · June 3, 2020, 1:52pm

Try this:

eric · June 3, 2020, 5:53pm

Is there a reason why you have Adaptive Streaming turned off? We haven't had any issues with it recently.

djcastaldo · June 3, 2020, 8:47pm

I guess I have it set that way because I don't need it and don't want one more thing running that I don't have control over. I don't have transcoding issues.

gregr · June 3, 2020, 8:51pm

Just now rebooted. DSM 6.2.3-25426.

Here are a few data points:

Transcoder: Hardware
Deinterlacer: Hardware
Adaptive Streaming: On
Server Quality: 1080p @ 10mbps
Runs at about 0.5x, CPU utilization about 12%

Transcoder: Hardware
Deinterlacer: Hardware
Adaptive Streaming: Off
Server Quality: 1080p @ 10mbps
Runs at about 0.6x, CPU utilization about 12%

Transcoder: Hardware
Deinterlacer: Hardware
Adaptive Streaming: Off
Server Quality: 720p @ 6mbps
Runs at about 1x, CPU utilization about 12%

Transcoder: Hardware
Deinterlacer: Hardware
Adaptive Streaming: Off
Server Quality: 1080p @ 6mbps
Runs at about 0.8x, CPU utilization about 12%

tmm1 · June 3, 2020, 9:02pm

Can you click Help > Submit Diagnostics on the web UI

gregr · June 3, 2020, 9:14pm

Done - logs have been submitted as df14670b-c2f3-4582-a829-87a20bf824cd

tmm1 · June 3, 2020, 9:19pm

Thanks. Do you recall when you last used it successfully and what speeds you were getting? Did you upgrade RAM in this NAS at any point?

gregr · June 3, 2020, 9:52pm

Gosh...not really. I know I used it in January, but I don't remember if I dialed the settings down a bunch to get it to work, or if it was just seamless. I think it pretty much just worked.

And I know a while back things seemed to work seamlessly on the default settings - this post comes to mind, where you guys worked around some issue you found (Synology DS-916+ not transcoding in real time (+ processing hooks)).

I know this is all kinda vague - sorry about that! I've been home since mid-January, so haven't really noticed the transcoding performance.

gregr · June 4, 2020, 7:53pm

Sorry, I missed your other question - I have not upgraded the RAM; it just has the 8GB it came with.