search

pesintta / vdr-plugin-vaapidevice

VDR VAAPIDevice Plug-in
9 stars 12 forks source link

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

Open Space2Man opened 6 years ago

Space2Man commented 6 years ago

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 commented 6 years ago

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 commented 6 years ago

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 commented 6 years ago

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 commented 6 years ago

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

mighty-p commented 6 years ago

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 commented 6 years ago

I still can reproduce the issue on current commit (53d44aabad06e7a93575cb7b5cf34801dc60136b) ... 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 commented 6 years ago

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

Space2Man commented 6 years ago

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

Space2Man commented 6 years ago

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 commented 6 years ago

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

Saman-VDR commented 6 years ago

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

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 commented 6 years ago

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 commented 6 years ago

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 commented 6 years ago

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

Saman-VDR commented 6 years ago

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 commented 6 years ago

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

jupe76 commented 6 years ago

@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 commented 6 years ago

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