Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

When switching to ZDF based transponder it takes several seconds until sound is heard #98

Open
Space2Man opened this issue Mar 11, 2018 · 18 comments

Comments

@Space2Man
Copy link

When switching to ZDF based transponder there is silence for several seconds, then the picture stops for a brief moment and then both sound and picture are available (in this case at 18:01:45):

Mar 11 18:01:37 [vdr] [10462] switching to channel 2 S19.2E-1-1011-11110 (ZDF HD)
Mar 11 18:01:37 [vdr] [10496] VAAPI: audio/alsa: using device 'hw:0,7'
Mar 11 18:01:37 [vdr] [10496] VAAPI: audio/alsa: start delay 336ms
Mar 11 18:01:45 [vdr] [10496] VAAPI: audio/alsa: using device 'hw:0,7'
Mar 11 18:01:45 [vdr] [10496] VAAPI: audio/alsa: start delay 336ms
Mar 11 18:01:45 [vdr] [10723] ERROR: TS packet not accepted in Transfer Mode
Mar 11 18:01:45 [vdr] [10505] VAAPI-ERROR: video: display buffer empty, duping frame (8983/440) 0
Mar 11 18:01:45 [vdr] [10505] VAAPI-ERROR: video: display buffer empty, duping frame (8984/440) 0
Mar 11 18:01:45 [vdr] [10505] VAAPI-ERROR: video: display buffer empty, duping frame (8985/440) 0
Mar 11 18:01:45 [vdr] [10505] VAAPI-ERROR: video: display buffer empty, duping frame (8986/440) 5
Mar 11 18:01:45 [vdr] [10505] VAAPI-ERROR: video: display buffer empty, duping frame (8987/440) 0
Mar 11 18:01:45 [vdr] [10505] VAAPI-ERROR: video: display buffer empty, duping frame (8988/440) 0
Mar 11 18:01:45 [vdr] [10505] VAAPI-ERROR: video: display buffer empty, duping frame (8989/440) 0
Mar 11 18:01:45 [vdr] [10505] VAAPI-ERROR: video: display buffer empty, duping frame (8990/440) 0
Mar 11 18:01:45 [vdr] [10505] VAAPI-ERROR: video: display buffer empty, duping frame (8991/440) 5

Best regards,

Jochen

VAAPIDEVICE SYSTEM INFORMATION REPORT

inxi
Resuming in non X mode: xdpyinfo not found. For package install advice run: inxi --recommends
System:    Kernel: 4.15.8-gentoo x86_64 bits: 64 gcc: 6.4.0 Console: tty 0
           Distro: Gentoo Base System release 2.4.1
Machine:   Device: desktop Mobo: ASUSTeK model: J3455M-E v: Rev X.0x serial: <filter>
           UEFI: American Megatrends v: 0604 date: 09/29/2017
CPU:       Quad core Intel Celeron J3455 (-MCP-) arch: N/A cache: 1024 KB
           flags: (lm nx sse sse2 sse3 sse4_1 sse4_2 ssse3 vmx) bmips: 11980
           clock speeds: max: 2300 MHz 1: 1616 MHz 2: 1889 MHz 3: 1988 MHz
           4: 1582 MHz
Graphics:  Card: Intel Device 5a85 bus-ID: 00:02.0
           Display Server: X.org 1.19.5 driver: intel
           tty size: 140x50 Advanced Data: N/A for root
Audio:     Card Intel Celeron N3350/Pentium N4200/Atom E3900 Series Audio Cluster
           driver: snd_hda_intel bus-ID: 00:0e.0
           Sound: Advanced Linux Sound Architecture v: k4.15.8-gentoo
vainfo
ffmpeg
ffmpeg version 3.3.6 Copyright (c) 2000-2017 the FFmpeg developers
built with gcc 6.4.0 (Gentoo 6.4.0-r1 p1.3)
libavutil      55. 58.100 / 55. 58.100
libavcodec     57. 89.100 / 57. 89.100
libavformat    57. 71.100 / 57. 71.100
libavdevice    57.  6.100 / 57.  6.100
libavfilter     6. 82.100 /  6. 82.100
libavresample   3.  5.  0 /  3.  5.  0
libswscale      4.  6.100 /  4.  6.100
libswresample   2.  7.100 /  2.  7.100
libpostproc    54.  5.100 / 54.  5.100
gcc
6.4.0
svdrpsend
220 <filter> SVDRP VideoDiskRecorder 2.3.8; Sun Mar 11 18:07:04 2018; UTF-8
900- Frames: missed(0) duped(24896) dropped(20) total(15907) PTS(22:41:30.581) drift(146) audio(423) video(0)
900- Video: h264/vaapi_vld 1280x720p 16:9 @ 1920x1080 - Intel i965 driver for Intel(R) Broxton - 2.1.0
900- Audio: mp2 48000Hz 2 channels
900 Command: vaapidevice -d :0.0 -v va-api -a hw:0,7 -f
221 <filter> closing connection
INCLUDE THIS REPORT INTO YOUR GITHUB ISSUE
@mighty-p
Copy link

I can confirm this one. For me, it happens intermittently (maybe in one of five cases). While for other channels, sound is there always immediately, for ZDF HD it sometimes takes several seconds as described by @Space2Man

@9000h
Copy link
Contributor

9000h commented Mar 11, 2018

Mar 11 18:01:45 [vdr] [10723] ERROR: TS packet not accepted in Transfer Mode
this is most probably the issue

make sure to check dmesg for driver issues like audio or video or dvb
I had to add for "options snd-hda-intel index=0 probe_mask=1" to get the audio driver right
and you may also need to add to grub
GRUB_CMDLINE_LINUX="pci=nocrs vmalloc=256M i915.enable_guc_loading=1 i915.enable_guc_submission=1"
this was needed here to get rid of gpu hangs etc ... on apollo lake
and if you use satip you need to tune the network for UDP

@Space2Man
Copy link
Author

One thing to note ... this happens also on other ZDF based channels like ZDFinfo. Picture is there right from the beginning but after some time there is this brief pause and then sound is there also.

There are no errors in dmesg btw and issue was only noticed on ZDF based channels ... maybe related to DD soundformat? Need to investigate during next downtime window :)

@Space2Man
Copy link
Author

And if I remember I only started to notice this recently ... but can't remember when it started ...

@mighty-p
Copy link

Yes, also noticed that only quite recently. But right now (with latest git state) I cannot reproduce it anymore. Not sure if this is coincidence, caused by the program currently sent, or if it has been fixed by one of today's commits (I believe I still saw it yesterday).

@Space2Man
Copy link
Author

I still can reproduce the issue on current commit (53d44aa) ... but it seems to happen only (and not every time) if I come from a different transponder ... If I switch between two ZDF transponders I could not reproduce it ... on my system with Nvidia + softhddev I can not reproduce ... I need to test with some old commits and see if I can find the place where it showed up for the first time.

@Saman-VDR
Copy link

I can confirm this issue too, but here the involved channels are not only from ZDF.

@Space2Man
Copy link
Author

Funny thing is ... by accident only the SATIP server was used after restart today and then I could not reproduce this issue ...

@Space2Man
Copy link
Author

after some testing SATIP completely got stuck and I saw lot's of

ERROR: TS packet not accepted in Transfer Mode

and the picture froze forever. But that's something completely different ... so I am not sure if this is related to Sat reception or something else ... if other people have the same issue it still might be an issue in vaapidevice. Because with Nvidia I never had this issue ...

@9000h
Copy link
Contributor

9000h commented Mar 13, 2018

have seen this also here but could not identify jet whats the root cause

@Saman-VDR
Copy link

With the last commit, the problem is still present.
I didn't get these kind of errors with 6372704

Mar 15 09:37:15 vdr vdr: [10427] switching to channel 15 S19.2E-1-1061-10354 (MDR Thüringen HD)
Mar 15 09:37:15 vdr vdr: [11182] device 1 TS buffer thread ended (pid=10427, tid=11182)
Mar 15 09:37:15 vdr vdr: [11181] buffer stats: 187060 (3%) used
Mar 15 09:37:15 vdr vdr: [11181] device 1 receiver thread ended (pid=10427, tid=11181)
Mar 15 09:37:15 vdr vdr: [11191] device 1 receiver thread started (pid=10427, tid=11191, prio=high)
Mar 15 09:37:15 vdr vdr: [11192] device 1 TS buffer thread started (pid=10427, tid=11192, prio=high)
Mar 15 09:37:16 vdr vdr: [11049] VAAPI: audio/alsa: using device 'pulse'
Mar 15 09:37:16 vdr vdr: [11049] VAAPI: audio/alsa: start delay 336ms
Mar 15 09:37:23 vdr vdr: [11049] VAAPI: audio/alsa: using device 'pulse'
Mar 15 09:37:23 vdr vdr: [11049] VAAPI: audio/alsa: start delay 336ms
Mar 15 09:37:23 vdr vdr: [11191] ERROR: TS packet not accepted in Transfer Mode
Mar 15 09:37:23 vdr vdr: [11018] VAAPI-ERROR: video: slow down video, duping frame
Mar 15 09:37:23 vdr vdr: [11018] VAAPI-ERROR: video: decoder buffer empty, duping frame (-520123152/0) 0 v-buf
Mar 15 09:37:23 vdr vdr: [11018] VAAPI: video: --:--:--.---   +0    0   0/\ms   0+1 v-buf
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: noise reduction supported
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: 0,00 - 1,00 ++ 0,03 = 0,50
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: enabling denoise filter (pos = 0)
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: deinterlacing supported
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: bob deinterlace supported
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: motion adaptive deinterlace supported
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: motion compensated deinterlace supported
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: enabling deinterlacing (pos = 1)
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: sharpening supported
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: 0,00 - 1,00 ++ 0,03 = 0,50
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: enabling sharpening filter (pos = 0)
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: enabling color balance filters
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: supported color balance filter count: 4
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: hue (-180,00 - 180,00 ++ 1,00 = 0,00) (pos = 2)
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: saturation (0,00 - 10,00 ++ 0,10 = 1,00) (pos = 2)
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: brightness (-100,00 - 100,00 ++ 1,00 = 0,00) (pos = 2)
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: contrast (0,00 - 10,00 ++ 0,10 = 1,00) (pos = 2)
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: skin tone enhancement supported
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: 0,00 - 4,00 ++ 1,00 = 0,00
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: enabling skin tone filter (pos = 3)
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: allocating 1 forward reference surfaces for postprocessing
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video/vaapi: allocating 0 backward reference surfaces for postprocessing
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: Changing to 0 forward reference surfaces for postprocessing
Mar 15 09:37:24 vdr vdr: [11018] VAAPI: video: 17:55:41.890+8888    0   0/\ms   0+2 v-buf
Mar 15 09:37:25 vdr vdr: [11018] VAAPI: video: initial slow down video, frame 56
Mar 15 09:37:25 vdr vdr: [11018] VAAPI: video: slow down video, duping frame
Mar 15 09:37:25 vdr vdr: [11018] VAAPI: video/vaapi: synced after 57 frames
Mar 15 09:37:25 vdr vdr: [11018] VAAPI: video: 17:55:41.890 +109  315   0/\ms  54+2 v-buf
Mar 15 09:38:01 vdr vdr: [10427] switching to channel 14 S19.2E-1-1061-10355 (hr-fernsehen HD)
Mar 15 09:38:01 vdr vdr: [11192] device 1 TS buffer thread ended (pid=10427, tid=11192)
Mar 15 09:38:01 vdr vdr: [11191] buffer stats: 384648 (7%) used
Mar 15 09:38:01 vdr vdr: [11191] device 1 receiver thread ended (pid=10427, tid=11191)
Mar 15 09:38:01 vdr vdr: [11220] device 1 receiver thread started (pid=10427, tid=11220, prio=high)
Mar 15 09:38:01 vdr vdr: [11221] device 1 TS buffer thread started (pid=10427, tid=11221, prio=high)
Mar 15 09:38:01 vdr vdr: [11049] VAAPI: audio/alsa: using device 'pulse'
Mar 15 09:38:01 vdr vdr: [11049] VAAPI: audio/alsa: start delay 336ms
Mar 15 09:38:09 vdr vdr: [11049] VAAPI: audio/alsa: using device 'pulse'
Mar 15 09:38:09 vdr vdr: [11049] VAAPI: audio/alsa: start delay 336ms
Mar 15 09:38:09 vdr vdr: [11220] ERROR: TS packet not accepted in Transfer Mode
Mar 15 09:38:09 vdr vdr: [11018] VAAPI-ERROR: video: slow down video, duping frame
Mar 15 09:38:09 vdr vdr: [11018] VAAPI-ERROR: video: decoder buffer empty, duping frame (-520123152/0) 0 v-buf
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video: --:--:--.---   +0    0   0/\ms   0+1 v-buf
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: noise reduction supported
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: 0,00 - 1,00 ++ 0,03 = 0,50
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: enabling denoise filter (pos = 0)
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: deinterlacing supported
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: bob deinterlace supported
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: motion adaptive deinterlace supported
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: motion compensated deinterlace supported
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: enabling deinterlacing (pos = 1)
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: sharpening supported
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: 0,00 - 1,00 ++ 0,03 = 0,50
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: enabling sharpening filter (pos = 0)
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: enabling color balance filters
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: supported color balance filter count: 4
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: hue (-180,00 - 180,00 ++ 1,00 = 0,00) (pos = 2)
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: saturation (0,00 - 10,00 ++ 0,10 = 1,00) (pos = 2)
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: brightness (-100,00 - 100,00 ++ 1,00 = 0,00) (pos = 2)
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: contrast (0,00 - 10,00 ++ 0,10 = 1,00) (pos = 2)
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: skin tone enhancement supported
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: 0,00 - 4,00 ++ 1,00 = 0,00
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: enabling skin tone filter (pos = 3)
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: allocating 1 forward reference surfaces for postprocessing
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video/vaapi: allocating 0 backward reference surfaces for postprocessing
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: Changing to 0 forward reference surfaces for postprocessing
Mar 15 09:38:09 vdr vdr: [11018] VAAPI: video: 17:18:11.282+8888    0   0/\ms   1+3 v-buf
Mar 15 09:38:10 vdr vdr: [11018] VAAPI: video: initial slow down video, frame 61
Mar 15 09:38:10 vdr vdr: [11018] VAAPI: video: slow down video, duping frame
Mar 15 09:38:10 vdr vdr: [11018] VAAPI: video/vaapi: synced after 62 frames
Mar 15 09:38:10 vdr vdr: [11018] VAAPI: video: 17:18:11.282 +104  395   0/\ms  60+2 v-buf
Mar 15 09:39:01 vdr vdr: [10437] SCR 1 assigned to device 3
Mar 15 09:39:01 vdr vdr: [10440] SCR 2 assigned to device 4
Mar 15 09:39:01 vdr vdr: [10434] SCR 3 assigned to device 2
Mar 15 09:39:02 vdr vdr: epg2vdr: Handler: Init handler instance for thread 10435
Mar 15 09:39:02 vdr vdr: epg2vdr: Handler: Trying to re-connect to database!
Mar 15 09:39:02 vdr vdr: epg2vdr: Handler: Connection established successfull!
Mar 15 09:39:03 vdr vdr: epg2vdr: Handler: Init handler instance for thread 10441
Mar 15 09:39:03 vdr vdr: epg2vdr: Handler: Init handler instance for thread 10438
Mar 15 09:39:03 vdr vdr: epg2vdr: Handler: Trying to re-connect to database!
Mar 15 09:39:03 vdr vdr: epg2vdr: Handler: Connection established successfull!
#### VAAPIDEVICE SYSTEM INFORMATION REPORT
##### inxi

System: Kernel: 4.15.0-11-generic x86_64 bits: 64 gcc: 7.3.0 Desktop: N/A
Distro: Ubuntu Bionic Beaver (development branch)
Machine: Device: desktop Mobo: ASRock model: J3455M serial: N/A UEFI: American Megatrends v: P1.60 date: 01/16/2018
CPU: Quad core Intel Celeron J3455 (-MCP-) arch: N/A cache: 1024 KB
flags: (lm nx sse sse2 sse3 sse4_1 sse4_2 ssse3 vmx) bmips: 11980
clock speeds: max: 2300 MHz 1: 2153 MHz 2: 2081 MHz 3: 2127 MHz 4: 2132 MHz
Graphics: Card: Intel Device 5a85 bus-ID: 00:02.0
Display Server: X.Org 1.19.6 driver: intel Resolution: 1920x1080@50.00hz
OpenGL: renderer: Mesa DRI Intel HD Graphics 500 (Broxton 2x6)
version: 4.5 Mesa 18.0.0-rc4 Direct Render: Yes
Audio: Card Intel Celeron N3350/Pentium N4200/Atom E3900 Series Audio Cluster
driver: snd_hda_intel bus-ID: 00:0e.0
Sound: Advanced Linux Sound Architecture v: k4.15.0-11-generic

##### vainfo

vainfo: VA-API version: 1.1 (libva 2.1.0)
vainfo: Driver version: Intel i965 driver for Intel(R) Broxton - 2.0.0
vainfo: Supported profile and entrypoints
VAProfileMPEG2Simple : VAEntrypointVLD
VAProfileMPEG2Main : VAEntrypointVLD
VAProfileH264ConstrainedBaseline: VAEntrypointVLD
VAProfileH264ConstrainedBaseline: VAEntrypointEncSlice
VAProfileH264ConstrainedBaseline: VAEntrypointEncSliceLP
VAProfileH264Main : VAEntrypointVLD
VAProfileH264Main : VAEntrypointEncSlice
VAProfileH264Main : VAEntrypointEncSliceLP
VAProfileH264High : VAEntrypointVLD
VAProfileH264High : VAEntrypointEncSlice
VAProfileH264High : VAEntrypointEncSliceLP
VAProfileH264MultiviewHigh : VAEntrypointVLD
VAProfileH264MultiviewHigh : VAEntrypointEncSlice
VAProfileH264StereoHigh : VAEntrypointVLD
VAProfileH264StereoHigh : VAEntrypointEncSlice
VAProfileVC1Simple : VAEntrypointVLD
VAProfileVC1Main : VAEntrypointVLD
VAProfileVC1Advanced : VAEntrypointVLD
VAProfileNone : VAEntrypointVideoProc
VAProfileJPEGBaseline : VAEntrypointVLD
VAProfileJPEGBaseline : VAEntrypointEncPicture
VAProfileVP8Version0_3 : VAEntrypointVLD
VAProfileHEVCMain : VAEntrypointVLD
VAProfileHEVCMain : VAEntrypointEncSlice
VAProfileHEVCMain10 : VAEntrypointVLD

##### ffmpeg

ffmpeg version 3.4.2-1build1 Copyright (c) 2000-2018 the FFmpeg developers
built with gcc 7 (Ubuntu 7.3.0-5ubuntu1)
libavutil 55. 78.100 / 55. 78.100
libavcodec 57.107.100 / 57.107.100
libavformat 57. 83.100 / 57. 83.100
libavdevice 57. 10.100 / 57. 10.100
libavfilter 6.107.100 / 6.107.100
libavresample 3. 7. 0 / 3. 7. 0
libswscale 4. 8.100 / 4. 8.100
libswresample 2. 9.100 / 2. 9.100
libpostproc 54. 7.100 / 54. 7.100

##### gcc

7

##### svdrpsend

220 SVDRP VideoDiskRecorder 2.3.8; Thu Mar 15 09:40:00 2018; UTF-8
900- Frames: missed(0) duped(11345) dropped(0) total(5500) PTS(17:20:01.242) drift(68) audio(318) video(0)
900- Video: h264/vaapi_vld 1280x720p 16:9 @ 1920x1080 - Intel i965 driver for Intel(R) Broxton - 2.0.0
900- Audio: mp2 48000Hz 2 channels
900 Command: vaapidevice -d :0.0 -D -f -w alsa-driver-broken -a pulse
221 closing connection

##### INCLUDE THIS REPORT INTO YOUR GITHUB ISSUE

@Space2Man
Copy link
Author

Hi,

even with current VDR + vaapidevice I could reproduce the issue:

Mar 18 17:23:44 [vdr] [30648] switching to channel 15 S19.2E-1-1039-10376 (ONE HD)
Mar 18 17:23:45 [vdr] [30685] VAAPI: audio/alsa: using device 'hw:0,7'
Mar 18 17:23:45 [vdr] [30685] VAAPI: audio/alsa: start delay 336ms
Mar 18 17:23:53 [vdr] [30685] VAAPI: audio/alsa: using device 'hw:0,7'
Mar 18 17:23:53 [vdr] [30685] VAAPI: audio/alsa: start delay 336ms
Mar 18 17:23:53 [vdr] [24356] ERROR: 1 TS packet(s) not accepted in Transfer Mode

And find attached the current report of my system:

...

VAAPIDEVICE SYSTEM INFORMATION REPORT

inxi
Resuming in non X mode: xdpyinfo not found. For package install advice run: inxi --recommends
System:    Kernel: 4.15.8-gentoo x86_64 bits: 64 gcc: 6.4.0 Console: tty 2
           Distro: Gentoo Base System release 2.4.1
Machine:   Device: desktop Mobo: ASUSTeK model: J3455M-E v: Rev X.0x serial: <filter>
           UEFI: American Megatrends v: 0604 date: 09/29/2017
CPU:       Quad core Intel Celeron J3455 (-MCP-) arch: N/A cache: 1024 KB
           flags: (lm nx sse sse2 sse3 sse4_1 sse4_2 ssse3 vmx) bmips: 11980
           clock speeds: max: 2300 MHz 1: 1418 MHz 2: 1966 MHz 3: 1916 MHz
           4: 1714 MHz
Graphics:  Card: Intel Device 5a85 bus-ID: 00:02.0
           Display Server: X.org 1.19.5 driver: intel
           tty size: 140x50 Advanced Data: N/A for root
Audio:     Card Intel Celeron N3350/Pentium N4200/Atom E3900 Series Audio Cluster
           driver: snd_hda_intel bus-ID: 00:0e.0
           Sound: Advanced Linux Sound Architecture v: k4.15.8-gentoo
vainfo
ffmpeg
ffmpeg version 3.3.6 Copyright (c) 2000-2017 the FFmpeg developers
built with gcc 6.4.0 (Gentoo 6.4.0-r1 p1.3)
libavutil      55. 58.100 / 55. 58.100
libavcodec     57. 89.100 / 57. 89.100
libavformat    57. 71.100 / 57. 71.100
libavdevice    57.  6.100 / 57.  6.100
libavfilter     6. 82.100 /  6. 82.100
libavresample   3.  5.  0 /  3.  5.  0
libswscale      4.  6.100 /  4.  6.100
libswresample   2.  7.100 /  2.  7.100
libpostproc    54.  5.100 / 54.  5.100
gcc
6.4.0
svdrpsend
220 <filter> SVDRP VideoDiskRecorder 2.3.9; Sun Mar 18 17:27:28 2018; UTF-8
900- Frames: missed(0) duped(26831) dropped(0) total(10689) PTS(18:12:03.505) drift(120) audio(311) video(0)
900- Video: h264/vaapi_vld 1280x720p 16:9 @ 1920x1080 - Intel i965 driver for Intel(R) Broxton - 2.1.0
900- Audio: mp2 48000Hz 2 channels
900 Command: vaapidevice -d :0.0 -v va-api -a hw:0,7 -f
221 <filter> closing connection
INCLUDE THIS REPORT INTO YOUR GITHUB ISSUE

...

@9000h
Copy link
Contributor

9000h commented Mar 18, 2018

the only change on the vdr-2.3.9 is rate limit the the logging to 1 per minute for the "TS packet not accepted in Transfer Mode" message, so nothing to expect here

@Space2Man
Copy link
Author

Yes, I know, but I thought from the commit message that maybe a6aac5f would have helped ...

@Saman-VDR
Copy link

For me this is a serious issue and I can't use the plugin in the current stage :(
Is there anything we can do to solve this?

@pesintta
Copy link
Owner

I'm unable to reproduce this issue. Does the plugin setting "Soft start a/v sync" have any effect?

@jupe76
Copy link

jupe76 commented Mar 22, 2018

@pesintta :
It seems to me that it is somewhat better with "Soft start a/v sync" on.
Anyway, it is still there. Here is a current log entry:

Mar 22 20:52:20 vdrtwo vdr[432]: [572] VAAPI: audio/alsa: using device 'hw:0,3'
Mar 22 20:52:20 vdrtwo vdr[432]: [572] VAAPI: audio/alsa: start delay 336ms
Mar 22 20:52:28 vdrtwo vdr[432]: [1732] ERROR: 1 TS packet(s) not accepted in Transfer Mode
Mar 22 20:52:28 vdrtwo vdr[432]: [572] VAAPI: audio/alsa: using device 'hw:0,3'
Mar 22 20:52:28 vdrtwo vdr[432]: [572] VAAPI: audio/alsa: start delay 336ms
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI-ERROR: video: slow down video, duping frame
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI-ERROR: video: decoder buffer empty, duping frame (-411050864/-668673872) 0 v-buf
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video: --:--:--.---   +0    0   0/\ms   0+1 v-buf
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: noise reduction supported
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: 0,00 - 1,00 ++ 0,03 = 0,50
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: enabling denoise filter (pos = 0)
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: deinterlacing supported
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: bob deinterlace supported
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: motion adaptive deinterlace supported
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: motion compensated deinterlace supported
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: enabling deinterlacing (pos = 1)
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: sharpening supported
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: 0,00 - 1,00 ++ 0,03 = 0,50
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: enabling sharpening filter (pos = 0)
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: enabling color balance filters
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: supported color balance filter count: 4
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: hue (-180,00 - 180,00 ++ 1,00 = 0,00) (pos = 2)
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: saturation (0,00 - 10,00 ++ 0,10 = 1,00) (pos = 2)
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: brightness (-100,00 - 100,00 ++ 1,00 = 0,00) (pos = 2)
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: contrast (0,00 - 10,00 ++ 0,10 = 1,00) (pos = 2)
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: skin tone enhancement supported
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: 0,00 - 4,00 ++ 1,00 = 0,00
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: enabling skin tone filter (pos = 3)
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: allocating 1 forward reference surfaces for postprocessing
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: allocating 0 backward reference surfaces for postprocessing
Mar 22 20:52:28 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: changing to 0 forward reference surfaces for postprocessing
Mar 22 20:52:29 vdrtwo vdr[432]: [573] VAAPI: video: decoder buffer empty, duping frame (59941/8) 0 v-buf
Mar 22 20:52:29 vdrtwo vdr[432]: [573] VAAPI: video: slow down video, duping frame
Mar 22 20:52:29 vdrtwo vdr[432]: [573] VAAPI: video: 24:21:33.864+1290  335   0/\ms   1+3 v-buf
Mar 22 20:52:32 vdrtwo vdr[432]: [573] VAAPI: video/vaapi: synced after 166 frames

VAAPIDEVICE SYSTEM INFORMATION REPORT

inxi
Resuming in non X mode: glxinfo not found. For package install advice run: inxi --recommends
System:    Kernel: 4.14.27-1-lts x86_64 bits: 64 gcc: 7.3.1 Desktop: Openbo
x 3.6.1 Distro: Arch Linux
Machine:   Device: desktop Mobo: Intel model: NUC7i3BNB v: J22859-306
serial: <filter>
           UEFI: Intel v: BNKBL357.86A.0060.2017.1214.2013 date: 12/14/2017
CPU:       Dual core Intel Core i3-7100U (-MT-MCP-) arch: Kaby Lake rev.9 cache:
3072 KB
           flags: (lm nx sse sse2 sse3 sse4_1 sse4_2 ssse3 vmx) bmips: 9600
           clock speeds: max: 2400 MHz 1: 799 MHz 2: 800 MHz 3:
 800 MHz 4: 800 MHz
Graphics:  Card: Intel HD Graphics 620 bus-ID: 00:02.0
           Display Server: X.org 1.19.6 drivers: modesetting (unloaded: vesa)
           tty size: 135x37 Advanced Data: N/A for root
Audio:     Card Intel Sunrise Point-LP HD Audio driver: snd_hda_intel bus-ID: 00:1f.3

           Sound: Advanced Linux Sound Architecture v: k4.14.27-1-lts
vainfo
vainfo: VA-API version: 1.1 (libva 2.1.0)
vainfo: Driver version: Intel i965 driver for Intel(R) Kaby Lake - 2.1.0
vainfo: Supported profile and entrypoints
      VAProfileMPEG2Simple            : VAEntrypointVLD
      VAProfileMPEG2Simple            : VAEntrypointEncSlice
      VAProfileMPEG2Main              : VAEntrypointVLD
      VAProfileMPEG2Main              : VAEntrypointEncSlice
      VAProfileH264ConstrainedBaseline: VAEntrypointVLD
      VAProfileH264ConstrainedBaseline: VAEntrypointEncSlice
      VAProfileH264ConstrainedBaseline: VAEntrypointEncSliceLP
      VAProfileH264Main               : VAEntrypointVLD
      VAProfileH264Main               : VAEntrypointEncSlice
      VAProfileH264Main               : VAEntrypointEncSliceLP
      VAProfileH264High               : VAEntrypointVLD
      VAProfileH264High               : VAEntrypointEncSlice
      VAProfileH264High               : VAEntrypointEncSliceLP
      VAProfileH264MultiviewHigh      : VAEntrypointVLD
      VAProfileH264MultiviewHigh      : VAEntrypointEncSlice
      VAProfileH264StereoHigh         : VAEntrypointVLD
      VAProfileH264StereoHigh         : VAEntrypointEncSlice
      VAProfileVC1Simple              : VAEntrypointVLD
      VAProfileVC1Main                : VAEntrypointVLD
      VAProfileVC1Advanced            : VAEntrypointVLD
      VAProfileNone                   : VAEntrypointVideoProc
      VAProfileJPEGBaseline           : VAEntrypointVLD
      VAProfileJPEGBaseline           : VAEntrypointEncPicture
      VAProfileVP8Version0_3          : VAEntrypointVLD
      VAProfileVP8Version0_3          : VAEntrypointEncSlice
      VAProfileHEVCMain               : VAEntrypointVLD
      VAProfileHEVCMain               : VAEntrypointEncSlice
      VAProfileHEVCMain10             : VAEntrypointVLD
      VAProfileHEVCMain10             : VAEntrypointEncSlice
      VAProfileVP9Profile0            : VAEntrypointVLD
      VAProfileVP9Profile0            : VAEntrypointEncSlice
      VAProfileVP9Profile2            : VAEntrypointVLD
ffmpeg
ffmpeg version 3.4.2 Copyright (c) 2000-2018 the FFmpeg developers
built with gcc 7.3.0 (GCC)
libavutil      55. 78.100 / 55. 78.100
libavcodec     57.107.100 / 57.107.100
libavformat    57. 83.100 / 57. 83.100
libavdevice    57. 10.100 / 57. 10.100
libavfilter     6.107.100 /  6.107.100
libavresample   3.  7.  0 /  3.  7.  0
libswscale      4.  8.100 /  4.  8.100
libswresample   2.  9.100 /  2.  9.100
libpostproc    54.  7.100 / 54.  7.100
gcc
7.3.0
svdrpsend
220 <filter> SVDRP VideoDiskRecorder 2.3.9; Thu Mar 22 21:07:47 2018; UTF-8
900- Frames: missed(0) duped(60051) dropped(0) total(7978) PTS(23:45:26.097) drift(51) audio(2967) video(0)
900- Video: h264/vaapi_vld 1280x720p 16:9 @ 1920x1080 - Intel i965 driver for Intel(R) Kaby Lake - 2.1.0
900- Audio: mp2 48000Hz 2 channels
900 Command: vaapidevice -a hw:0,3 -p hw:0,3 -D -f -w alsa-driver-broken -w alsa-close-open-delay
221 <filter> closing connection
INCLUDE THIS REPORT INTO YOUR GITHUB ISSUE

@Space2Man
Copy link
Author

Space2Man commented Mar 22, 2018

Can I do anything to help trace this issue? Some log level?

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

6 participants