NetBSD-Users archive
[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index][Old Index]

Re: audio1(vcaudio0): device timeout



El 26/5/25 a las 8:43, Ramiro Aceves escribió:

Hello,
After the success with gnuradio I decided to test another useful hamradio program. I started playing with my Afedri-Net receiver with ham/gqrx software under NetBSD Raspberrypi 4 and after a few seconds the waterfall screen hung. I first suspected from network cause receiver is connected through ethernet, but I soon discovered that deactivating the audio demodulator,  waterfall continued working (obviously without audio output).
 After several hours of thinking, I came to the conclusion that it was an audio driver related issue. 

dmesg output is full of messages like theese:

[ 76001,055812] audio1(vcaudio0): device timeout
[ 76004,055832] audio1(vcaudio0): device timeout
[ 76007,055853] audio1(vcaudio0): device timeout
[ 76225,035004] audio1(vcaudio0): device timeout
[ 76228,061940] audio1(vcaudio0): device timeout
[ 76231,061957] audio1(vcaudio0): device timeout
[ 76501,061762] audio1(vcaudio0): device timeout
[ 76504,061781] audio1(vcaudio0): device timeout
[ 76507,061842] audio1(vcaudio0): device timeout
[ 76694,616746] audio1(vcaudio0): device timeout
[ 76697,616761] audio1(vcaudio0): device timeout
[ 76700,616776] audio1(vcaudio0): device timeout
[ 76871,574464] audio1(vcaudio0): device timeout
[ 76874,574479] audio1(vcaudio0): device timeout
[ 76877,574494] audio1(vcaudio0): device timeout

If I try to "audiocfg test" the device fails:

netbsd-raspa4# audiocfg list
0: [*] audio1 @ vcaudio0: vchiq auds 3
        playback: 2ch, 48000Hz
        record:   unavailable
        (P-) slinear_le 16/16, 2ch, 48000-48000Hz
1: [ ] audio0 @ uaudio0: USB audio
        playback: unavailable
        record:   2ch, 32000Hz
        (-R) slinear_le 16/16, 2ch, { 16000 }
        (-R) slinear_le 16/16, 2ch, { 24000 }
        (-R) slinear_le 16/16, 2ch, { 32000 }
netbsd-raspa4#

netbsd-raspa4# audiocfg test 0
0: [*] audio1 @ vcaudio0: vchiq auds 3
        playback: 2ch, 48000Hz
        record:   unavailable
        (P-) slinear_le 16/16, 2ch, 48000-48000Hz
   testing channel 0...audiocfg: write: Resource temporarily unavailable
netbsd-raspa4#
After some hours I tried again and it miraclously restablished itself and without a reboot it continued working fine for several hours. Also tested that audacity worked fine sending output to the audio device. I was happy but thought that the bug would return again. 

This morning I started the receiver and the problem appeared again.
Is it a driver issue? How can I restart the audio to recover from that situation? should I fill a bug report? 

Thanks so much.

Regards.

Ramiro.






Hello:
I still do not understanding anything. GQRX and thus NetBSD audio has been working fine for many hours periods during the past days. But today I started GQRX and waterfall is stuck again. Audio device is stuck 

netbsd-raspa4$ audiocfg list
0: [*] audio1 @ vcaudio0: vchiq auds 3
       playback: 2ch, 48000Hz
       record:   unavailable
       (P-) slinear_le 16/16, 2ch, 48000-48000Hz
1: [ ] audio0 @ uaudio0: USB audio
       playback: unavailable
       record:   2ch, 32000Hz
       (-R) slinear_le 16/16, 2ch, { 16000 }
       (-R) slinear_le 16/16, 2ch, { 24000 }
       (-R) slinear_le 16/16, 2ch, { 32000 }

netbsd-raspa4$ audiocfg test 0
0: [*] audio1 @ vcaudio0: vchiq auds 3
       playback: 2ch, 48000Hz
       record:   unavailable
       (P-) slinear_le 16/16, 2ch, 48000-48000Hz
  testing channel 0...audiocfg: write: Resource temporarily unavailable
netbsd-raspa4$

If I try to play a file:

netbsd-raspa4$ audioplay piano2.wav
audioplay: write failed
netbsd-raspa4$

netbsd-raspa4# cat /var/log/messages
...
...
...
...
May 28 22:00:01 netbsd-raspa4 syslogd[695]: restart
May 29 07:31:51 netbsd-raspa4 dbus-daemon[18894]: [session uid=1000 pid=12185] Activating service name='org.a11y.Bus' requested by ':1.0' (uid=1000 pid=7117 comm="xfce4-session") May 29 07:31:52 netbsd-raspa4 dbus-daemon[18894]: [session uid=1000 pid=12185] Activating service name='org.gtk.vfs.Daemon' requested by ':1.1' (uid=1000 pid=5233 comm="/usr/pkg/libexec/at-spi-bus-launcher") May 29 07:31:52 netbsd-raspa4 dbus-daemon[18894]: [session uid=1000 pid=12185] Successfully activated service 'org.gtk.vfs.Daemon' May 29 07:31:52 netbsd-raspa4 dbus-daemon[18894]: [session uid=1000 pid=12185] Successfully activated service 'org.a11y.Bus' May 29 07:31:52 netbsd-raspa4 dbus-daemon[18894]: [session uid=1000 pid=12185] Activating service name='org.xfce.Xfconf' requested by ':1.4' (uid=1000 pid=7117 comm="xfce4-session") May 29 07:31:52 netbsd-raspa4 dbus-daemon[18894]: [session uid=1000 pid=12185] Successfully activated service 'org.xfce.Xfconf' May 29 07:32:31 netbsd-raspa4 /netbsd: [ 139399.4186533] audio1(vcaudio0): device timeout May 29 07:32:34 netbsd-raspa4 /netbsd: [ 139402.4186881] audio1(vcaudio0): device timeout May 29 07:32:37 netbsd-raspa4 /netbsd: [ 139405.4187233] audio1(vcaudio0): device timeout May 29 07:32:40 netbsd-raspa4 /netbsd: [ 139408.4187539] audio1(vcaudio0): device timeout May 29 07:32:43 netbsd-raspa4 /netbsd: [ 139411.4187852] audio1(vcaudio0): device timeout May 29 07:32:46 netbsd-raspa4 /netbsd: [ 139414.4188035] audio1(vcaudio0): device timeout May 29 07:32:49 netbsd-raspa4 /netbsd: [ 139417.4188223] audio1(vcaudio0): device timeout May 29 07:32:52 netbsd-raspa4 /netbsd: [ 139420.4188449] audio1(vcaudio0): device timeout May 29 07:32:55 netbsd-raspa4 /netbsd: [ 139423.4188732] audio1(vcaudio0): device timeout May 29 07:32:58 netbsd-raspa4 /netbsd: [ 139426.4325347] audio1(vcaudio0): device timeout May 29 07:33:01 netbsd-raspa4 /netbsd: [ 139429.4325544] audio1(vcaudio0): device timeout May 29 07:33:04 netbsd-raspa4 /netbsd: [ 139432.4325799] audio1(vcaudio0): device timeout May 29 07:33:07 netbsd-raspa4 /netbsd: [ 139435.4326054] audio1(vcaudio0): device timeout May 29 07:33:10 netbsd-raspa4 /netbsd: [ 139438.4326398] audio1(vcaudio0): device timeout May 29 07:33:13 netbsd-raspa4 /netbsd: [ 139441.4326634] audio1(vcaudio0): device timeout May 29 07:33:16 netbsd-raspa4 /netbsd: [ 139444.4327096] audio1(vcaudio0): device timeout May 29 07:33:19 netbsd-raspa4 /netbsd: [ 139447.4327400] audio1(vcaudio0): device timeout May 29 07:33:22 netbsd-raspa4 /netbsd: [ 139450.4327643] audio1(vcaudio0): device timeout May 29 07:33:27 netbsd-raspa4 /netbsd: [ 139455.0827995] audio1(vcaudio0): device timeout May 29 07:33:50 netbsd-raspa4 /netbsd: [ 139478.6229857] audio1(vcaudio0): device timeout May 29 07:33:53 netbsd-raspa4 /netbsd: [ 139481.6230016] audio1(vcaudio0): device timeout May 29 07:33:56 netbsd-raspa4 /netbsd: [ 139484.6230227] audio1(vcaudio0): device timeout May 29 07:35:52 netbsd-raspa4 /netbsd: [ 139599.7839290] audio1(vcaudio0): device timeout May 29 07:35:55 netbsd-raspa4 /netbsd: [ 139602.7839452] audio1(vcaudio0): device timeout May 29 07:35:58 netbsd-raspa4 /netbsd: [ 139605.7839609] audio1(vcaudio0): device timeout May 29 08:35:59 netbsd-raspa4 /netbsd: [ 143207.0425856] audio1(vcaudio0): device timeout May 29 08:36:02 netbsd-raspa4 /netbsd: [ 143210.0426007] audio1(vcaudio0): device timeout May 29 08:36:05 netbsd-raspa4 /netbsd: [ 143213.0426159] audio1(vcaudio0): device timeout May 29 08:36:48 netbsd-raspa4 /netbsd: [ 143256.0828538] audio1(vcaudio0): device timeout May 29 08:36:51 netbsd-raspa4 /netbsd: [ 143259.0828690] audio1(vcaudio0): device timeout May 29 08:36:54 netbsd-raspa4 /netbsd: [ 143262.0828846] audio1(vcaudio0): device timeout May 29 08:37:22 netbsd-raspa4 /netbsd: [ 143289.7830330] audio1(vcaudio0): device timeout May 29 08:37:25 netbsd-raspa4 /netbsd: [ 143292.7830480] audio1(vcaudio0): device timeout May 29 08:37:28 netbsd-raspa4 /netbsd: [ 143295.7830631] audio1(vcaudio0): device timeout May 29 08:38:28 netbsd-raspa4 /netbsd: [ 143355.7533826] audio1(vcaudio0): device timeout May 29 08:38:31 netbsd-raspa4 /netbsd: [ 143358.7533975] audio1(vcaudio0): device timeout May 29 08:38:34 netbsd-raspa4 /netbsd: [ 143361.7534127] audio1(vcaudio0): device timeout May 29 08:39:22 netbsd-raspa4 /netbsd: [ 143409.7936634] audio1(vcaudio0): setting play.port=0 failed: errno=22 May 29 08:39:22 netbsd-raspa4 /netbsd: [ 143409.8036639] audio1(vcaudio0): setting play.port=0 failed: errno=22 May 29 08:39:27 netbsd-raspa4 /netbsd: [ 143414.8036923] audio1(vcaudio0): device timeout May 29 08:39:30 netbsd-raspa4 /netbsd: [ 143417.8037075] audio1(vcaudio0): device timeout May 29 09:13:16 netbsd-raspa4 /netbsd: [ 145443.7639592] audio1(vcaudio0): device timeout May 29 09:13:19 netbsd-raspa4 /netbsd: [ 145446.7639746] audio1(vcaudio0): device timeout May 29 09:13:22 netbsd-raspa4 /netbsd: [ 145449.7639898] audio1(vcaudio0): device timeout May 29 09:32:33 netbsd-raspa4 /netbsd: [ 146600.8898286] audio1(vcaudio0): device timeout May 29 09:32:36 netbsd-raspa4 /netbsd: [ 146603.8898438] audio1(vcaudio0): device timeout May 29 09:32:39 netbsd-raspa4 /netbsd: [ 146606.8898591] audio1(vcaudio0): device timeout May 29 09:43:34 netbsd-raspa4 /netbsd: [ 147261.6932188] audio1(vcaudio0): device timeout May 29 09:43:37 netbsd-raspa4 /netbsd: [ 147264.6932341] audio1(vcaudio0): device timeout May 29 09:43:46 netbsd-raspa4 /netbsd: [ 147273.7932830] audio1(vcaudio0): device timeout May 29 09:43:49 netbsd-raspa4 /netbsd: [ 147276.7932981] audio1(vcaudio0): device timeout May 29 09:44:57 netbsd-raspa4 dbus-daemon[4001]: [session uid=1000 pid=25482] Activating service name='org.a11y.Bus' requested by ':1.0' (uid=1000 pid=14563 comm="xfce4-session") May 29 09:44:57 netbsd-raspa4 dbus-daemon[4001]: [session uid=1000 pid=25482] Activating service name='org.gtk.vfs.Daemon' requested by ':1.1' (uid=1000 pid=1178 comm="/usr/pkg/libexec/at-spi-bus-launcher") May 29 09:44:57 netbsd-raspa4 dbus-daemon[4001]: [session uid=1000 pid=25482] Successfully activated service 'org.gtk.vfs.Daemon' May 29 09:44:57 netbsd-raspa4 dbus-daemon[4001]: [session uid=1000 pid=25482] Successfully activated service 'org.a11y.Bus' May 29 09:44:57 netbsd-raspa4 dbus-daemon[4001]: [session uid=1000 pid=25482] Activating service name='org.xfce.Xfconf' requested by ':1.4' (uid=1000 pid=14563 comm="xfce4-session") May 29 09:44:57 netbsd-raspa4 dbus-daemon[4001]: [session uid=1000 pid=25482] Successfully activated service 'org.xfce.Xfconf' May 29 10:11:45 netbsd-raspa4 dbus-daemon[4001]: [session uid=1000 pid=25482] Activating service name='org.xfce.Xfconf' requested by ':1.11' (uid=1000 pid=13681 comm="xfwm4") May 29 10:11:45 netbsd-raspa4 dbus-daemon[4001]: [session uid=1000 pid=25482] Successfully activated service 'org.xfce.Xfconf' May 29 10:25:35 netbsd-raspa4 dbus-daemon[4001]: [session uid=1000 pid=25482] Activating service name='org.xfce.Xfconf' requested by ':1.26' (uid=1000 pid=17475 comm="xfce4-terminal --geometry=80x24 --role=xfce4-termi") May 29 10:25:35 netbsd-raspa4 dbus-daemon[4001]: [session uid=1000 pid=25482] Successfully activated service 'org.xfce.Xfconf' May 29 10:25:51 netbsd-raspa4 /netbsd: [ 149798.8692007] audio1(vcaudio0): device timeout May 29 10:25:54 netbsd-raspa4 /netbsd: [ 149801.8692196] audio1(vcaudio0): device timeout May 29 10:25:57 netbsd-raspa4 /netbsd: [ 149804.8692425] audio1(vcaudio0): device timeout May 29 10:26:11 netbsd-raspa4 /netbsd: [ 149819.5395216] audio1(vcaudio0): device timeout May 29 10:26:14 netbsd-raspa4 /netbsd: [ 149822.5395420] audio1(vcaudio0): device timeout 
netbsd-raspa4#


Do you know what can I do to get  useful debug information?

Thanks.
Ramiro.
Home | Main Index | Thread Index | Old Index