Playback stopped functioning

Sefton · December 17, 2023, 9:09am

Anyone familar with these logs see anything obvious about ‘why’ I get no playback (spotify, youtube, anything) ??

[Vollibrespot] : Alsa error PCM open ALSA function ‘snd_pcm_open’ failed with error ‘EINVAL: Invalid argument’
[Vollibrespot] : Could not start audio: Alsa error: PCM open failed

It was working (but always seems to need multiple reboots of volumio / my desktop, etc etc when for no obvious reasons it will start working)… been away a couple of weeks, now nothing works…

Pi 3B+ IQAudioDAC+ line out…

Starting Live Log...
info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
info: Device is now in test mode
Test mode enabled
No alpha test mode
info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
info: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
Soliciting pool server 2001:deb:0:4070::33
[Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443"
[Vollibrespot] : Authenticated as "seftonh" !
[Vollibrespot] : Using alsa sink
[Vollibrespot] : Metadata pipe established
[Vollibrespot] : Country: "TH"
[Vollibrespot] : Event: Volume { volume_to_mixer: 14784 }
[SpotifyConnect] Volume Spotify: 22.55893797207599 Volumio: 23
[Vollibrespot] : Event: SessionActive { became_active_at: 1702803658765 }
[Vollibrespot] : SessionActive!
[Vollibrespot] : Fetching autoplay context uri
[Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 220688590318136437930566313621872846373, audio_type: Track } }
[Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 220688590318136437930566313621872846373, audio_type: Track } }
[SpotifyConnect]  A connect session has begun
info: Acquiring new spotify session
info: CoreCommandRouter::volumioStop
info: CoreStateMachine::stop
info: CoreStateMachine::setConsumeUpdateService undefined
info: Checking Spotify Web API
[SpotifyConnect] Vollibrespot Active
info: CoreStateMachine::setConsumeUpdateService undefined
info: [1702803658774] ControllerSpotify::pushState
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
[SpotifyConnect]  Device palyback is active!
[SpotifyConnect]  Device palyback is loading
[Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:37i9dQZF1E38XMDz9vh6o9">
[Vollibrespot] : Resolving uri "spotify:station:playlist:37i9dQZF1E38XMDz9vh6o9"
info: touch_display: Setting screensaver timeout to 0 seconds.
[Vollibrespot] : Event: GotToken { token: Token { access_token: "BQB08AM4oWZCzSkM6HsLFDmYsTtOB7g20h-ShxRxn935YPgxJ6GN0AbeAbQcPr14poW7uyGPB8N4toaP45o6k_GR7jsjUOAMNIlTM566RYELwgb8T9jl1P70lahNtsYfXNc9mmP3U2ltQmYEge0r1YUQk2rk1DTWxKoy_syzgttEndM4GYbgCqgxv-uBsCi-Pw", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } }
[Vollibrespot] : ReqToken
info: New Spotify Access Token Received
info: Initializing Spotify Web API
info: CoreCommandRouter::volumioGetState
[Vollibrespot] : Loading  with Spotify URI 
info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
info: touch_display: Setting screensaver timeout to 0 seconds.
info: New Spotify Access Token Received
info: Initializing Spotify Web API
[SpotifyConnect] Setting VLS device_id: d1387d4d45e42ffef0462b9f0896cc0f7b7c672d
[SpotifyConnect] Setting VLS device_id: d1387d4d45e42ffef0462b9f0896cc0f7b7c672d
[Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:37i9dQZF1E38XMDz9vh6o9">
[Vollibrespot] :  (451733 ms) loaded
[Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EINVAL: Invalid argument'
[Vollibrespot] : Could not start audio: Alsa error: PCM open failed
[Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EINVAL: Invalid argument'
[Vollibrespot] : Could not start audio: Alsa error: PCM open failed
[Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EINVAL: Invalid argument'
[Vollibrespot] : Could not start audio: Alsa error: PCM open failed
thread '' panicked at 'called `Option::unwrap()` on a `None` value', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/audio_backend/alsa.rs:129:35
stack backtrace:
pcm512x 1-004c: ASoC: error at snd_soc_pcm_component_pm_runtime_get on pcm512x.1-004c: -22
 IQaudIO DAC: ASoC: error at __soc_pcm_open on IQaudIO DAC: -22
pcm512x 1-004c: ASoC: error at snd_soc_pcm_component_pm_runtime_get on pcm512x.1-004c: -22
 IQaudIO DAC: ASoC: error at __soc_pcm_open on IQaudIO DAC: -22
pcm512x 1-004c: ASoC: error at snd_soc_pcm_component_pm_runtime_get on pcm512x.1-004c: -22
 IQaudIO DAC: ASoC: error at __soc_pcm_open on IQaudIO DAC: -22
   0:   0xa58444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
   1:   0xa58444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
   2:   0xa58444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5
   3:   0xa58444 - ::fmt::h33b23b1d14f668ff
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22
   4:   0xa810dc - core::fmt::write::h6320fb0e6ac90e58
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17
   5:   0xa504c4 - std::io::Write::write_fmt::h33b25d4810ca481f
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15
   6:   0xa5aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5
   7:   0xa5aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9
   8:   0xa5aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22
   9:   0xa5ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9
  10:   0xa5b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17
  11:   0xa5b360 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:586:13
  12:   0xa589c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18
  13:   0xa5b098 - rust_begin_unwind
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5
  14:   0x53e6f4 - core::panicking::panic_fmt::h84c42730e4a50809
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14
  15:   0x53e5c8 - core::panicking::panic::h929d4344847bfdba
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:48:5
  16:   0x679158 - ::write::h2857e81996e06a1e
  17:   0x65b814 - ::poll::hf6331a81451dbb28
  18:   0x5836c0 - futures::task_impl::std::set::hb731921744f9247e
  19:   0x574708 - futures::task_impl::Spawn::poll_future_notify::h08f2c87ba9a4eead
  20:   0x588158 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb598e9d2970dbf13
  21:   0x5b4e70 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h7f140b0cfa5289e1
  22:   0xa5febc -  as core::ops::function::FnOnce>::call_once::hbc4d03a1815e028a
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/alloc/src/boxed.rs:1853:9
  23:   0xa5febc -  as core::ops::function::FnOnce>::call_once::h6055603e421418e8
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/alloc/src/boxed.rs:1853:9
  24:   0xa5febc - std::sys::unix::thread::Thread::new::thread_start::h0518d9d0e6b0ba31
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys/unix/thread.rs:108:17

dvo · December 17, 2023, 10:53am

this looks like spotify plugin
is asla set oke?

Sefton · December 17, 2023, 10:57am

Same thing for all plug ins… Youtube spotify etc… Nothing works and it was working and simply went on a holiday for a while…

ALSA ?? I am not familiar with this app so I am guessing you mean audio output ?? Its correctly set, as it was, for the IQAudioDAC+… No changes.

dvo · December 17, 2023, 10:58am

did you update volumio ? what version are you running?
asla is most of the time your audio settings from your dac what plugins are your running?
it could be that a plugin could giving problems.
do you have a premium account btw? you could ask for help if you have it.

Sefton · December 17, 2023, 11:12am

No not premium… So far its not been really elegant / stable enough for me…

I use chromecast audios usually but thats not playing nice youtube / win 11 (seems a known issue) so still bouncing around options.

Honestly I wonder if the 3B+ has the power for it… At times it all seems a bit laggy and strange

dvo · December 17, 2023, 11:14am

do you use extentions on win11 for youtube?

Sefton · December 17, 2023, 11:14am

will do a factory reset… was trying to avoid it as my default repo is not good so it needs to edit the sources etc… just all a hassle

Sefton · December 17, 2023, 11:14am

adblockers…

dvo · December 17, 2023, 11:17am

RYS - Remove YouTube Suggestions , YouTube nonstop, costom scrollbar,scrollbar remover, better volume booster more tweaks like Ublock origin.

if you want to filter it all i have most of it filtered.

i have backup settings for RYS and for fire fox how to remove toasters.
what browser do you use FireFox or brave ?
only the start page is gone if you don’t consent your history:P

Sefton · December 17, 2023, 10:57pm

But spotify isnt working… As well as spotify, mixcloud, soundcloud, etc.

dvo · December 18, 2023, 3:39am

i would create a log

post the link so we can look for any problems…

Sefton · December 18, 2023, 6:29am

http://logs.volumio.org/volumio/HmIb3aN.html

Seems the same thing

[Vollibrespot] : Alsa error PCM open ALSA function ‘snd_pcm_open’ failed with error ‘EINVAL: Invalid argument’
[Vollibrespot] : Could not start audio: Alsa error: PCM open failed


info: CoreCommandRouter::volumioGetState
info: CoreCommandRouter::volumioGetQueue
info: CoreStateMachine::getQueue
info: CorePlayQueue::getQueue
info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
[Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443"
[Vollibrespot] : Authenticated as "seftonh" !
[Vollibrespot] : Using alsa sink
[Vollibrespot] : Metadata pipe established
[Vollibrespot] : Country: "TH"
[Vollibrespot] : Event: Volume { volume_to_mixer: 14784 }
[SpotifyConnect] Volume Spotify: 22.55893797207599 Volumio: 23
[Vollibrespot] : Event: SessionActive { became_active_at: 1702880846037 }
[Vollibrespot] : SessionActive!
[Vollibrespot] : Fetching autoplay context uri
[Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 85309082760203731553003974797975915333, audio_type: Track } }
[Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 85309082760203731553003974797975915333, audio_type: Track } }
[SpotifyConnect]  A connect session has begun
info: Acquiring new spotify session
info: CoreCommandRouter::volumioStop
info: CoreStateMachine::stop
info: CoreStateMachine::serviceStop
info: CoreCommandRouter::serviceStop
info: Checking Spotify Web API
[SpotifyConnect] Vollibrespot Active
info: CoreStateMachine::setConsumeUpdateService undefined
info: [1702880846047] ControllerSpotify::pushState
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioPushState
[SpotifyConnect]  Device palyback is active!
[SpotifyConnect]  Device palyback is loading
info: touch_display: Setting screensaver timeout to 0 seconds.
info: CoreCommandRouter::volumioGetState
[Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:37i9dQZF1E38XMDz9vh6o9">
[Vollibrespot] : Resolving uri "spotify:station:playlist:37i9dQZF1E38XMDz9vh6o9"
info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
info: touch_display: Setting screensaver timeout to 0 seconds.
[Vollibrespot] : Event: GotToken { token: Token { access_token: "BQDvSgeHhc7Ym-4GZf2S6We5FlMcVy2E7Wplr_9Je2Ay02ELxKPtuEzm3gaM1_rO1R4aSAqtcxTh37mr85QLzP_NONchdtDE34wYsW_bmFhu5edrXGjC2cDliurYQG7FpSkCkWhwVCCedPdeMrtxts9CJ3ZAy7a9xWt2m8a13mjifImuKEe5pSK4m8isFurvMw", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } }
info: New Spotify Access Token Received
info: Initializing Spotify Web API
[Vollibrespot] : Loading  with Spotify URI 
[SpotifyConnect] Setting VLS device_id: d1387d4d45e42ffef0462b9f0896cc0f7b7c672d
[Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:37i9dQZF1E38XMDz9vh6o9">
[Vollibrespot] :  (200000 ms) loaded
[Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EINVAL: Invalid argument'
[Vollibrespot] : Could not start audio: Alsa error: PCM open failed
[Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EINVAL: Invalid argument'
[Vollibrespot] : Could not start audio: Alsa error: PCM open failed
[Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EINVAL: Invalid argument'
[Vollibrespot] : Could not start audio: Alsa error: PCM open failed
thread '' panicked at 'called `Option::unwrap()` on a `None` value', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/audio_backend/alsa.rs:129:35
stack backtrace:
pcm512x 1-004c: ASoC: error at snd_soc_pcm_component_pm_runtime_get on pcm512x.1-004c: -22
 IQaudIO DAC: ASoC: error at __soc_pcm_open on IQaudIO DAC: -22
pcm512x 1-004c: ASoC: error at snd_soc_pcm_component_pm_runtime_get on pcm512x.1-004c: -22
 IQaudIO DAC: ASoC: error at __soc_pcm_open on IQaudIO DAC: -22
pcm512x 1-004c: ASoC: error at snd_soc_pcm_component_pm_runtime_get on pcm512x.1-004c: -22
 IQaudIO DAC: ASoC: error at __soc_pcm_open on IQaudIO DAC: -22
   0:   0xa28444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
   1:   0xa28444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
   2:   0xa28444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5
   3:   0xa28444 - ::fmt::h33b23b1d14f668ff
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22
   4:   0xa510dc - core::fmt::write::h6320fb0e6ac90e58
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17
   5:   0xa204c4 - std::io::Write::write_fmt::h33b25d4810ca481f
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15
   6:   0xa2aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5
   7:   0xa2aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9
   8:   0xa2aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22
   9:   0xa2ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9
  10:   0xa2b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17
  11:   0xa2b360 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:586:13
  12:   0xa289c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18
  13:   0xa2b098 - rust_begin_unwind
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5
  14:   0x50e6f4 - core::panicking::panic_fmt::h84c42730e4a50809
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14
  15:   0x50e5c8 - core::panicking::panic::h929d4344847bfdba
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:48:5
  16:   0x649158 - ::write::h2857e81996e06a1e
  17:   0x62b814 - ::poll::hf6331a81451dbb28
  18:   0x5536c0 - futures::task_impl::std::set::hb731921744f9247e
  19:   0x544708 - futures::task_impl::Spawn::poll_future_notify::h08f2c87ba9a4eead
  20:   0x558158 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb598e9d2970dbf13
  21:   0x584e70 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h7f140b0cfa5289e1
  22:   0xa2febc -  as core::ops::function::FnOnce>::call_once::hbc4d03a1815e028a
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/alloc/src/boxed.rs:1853:9
  23:   0xa2febc -  as core::ops::function::FnOnce>::call_once::h6055603e421418e8
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/alloc/src/boxed.rs:1853:9
  24:   0xa2febc - std::sys::unix::thread::Thread::new::thread_start::h0518d9d0e6b0ba31
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys/unix/thread.rs:108:17
info: CALLMETHOD: system_controller system sendBugReport [object Object]
info: CoreCommandRouter::executeOnPlugin: system , sendBugReport
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
 volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
info: Log sent successfully, reply: {"status":"OK","link":"http://logs.volumio.org/volumio/HmIb3aN.html"}

Sefton · December 18, 2023, 6:33am

unless anyone has any great insight I will just do a full factory reset and rebuild it again…

Sefton · December 18, 2023, 7:19am

OK… So I have now…

Pulled the screen off the Pi… Factory reset… Did the set up, as best I can remember exactly as I did before… Installed only the spotify plug in… and its still doing this

time=“2023-12-18T14:15:19+07:00” level=warning msg=“failed handling dealer request” error=“failed loading current track (transfer): failed creating stream: ALSA error at snd_pcm_open: Invalid argument”
pcm512x 1-004c: ASoC: error at snd_soc_pcm_component_pm_runtime_get on pcm512x.1-004c: -22
IQaudIO DAC: ASoC: error at __soc_pcm_open on IQaudIO DAC: -22
time=“2023-12-18T14:15:19+07:00” level=debug msg=“skipping dealer message” uri=social-connect/v2/session_update

Something about the IQAudio / ALSA seems to fail

Full log here…

http://logs.volumio.org/volumio/2BdnA9Z.html

I mean I guess its conceivable my DAC hat has somehow failed ?? But it seems to me wildly unlikely.

Sefton · December 18, 2023, 10:08am

http://logs.volumio.org/volumio/OQc94rg.html

volumio · December 18, 2023, 10:48am

It might be that the DAC is gone, or the contacts are oxidized… What about removing the DAC, use some contact cleaner and try connecting it again?

Sefton · December 18, 2023, 11:29am

I will try… but its a 6 month old DAC… shiny new looking…

Headphones seem to work (I didnt actually conect to speakers but playback all appeared to connect and work) so its for sure something about the DAC.

http://logs.volumio.org/volumio/045m9kf.html

Sefton · December 18, 2023, 11:31am

Because I cant remember how I set it up last time…

I should have (in playback options) I2S DAC enabled and IQAudio selected in that drop down ??

I also tried it selecting the DAC visible in I2S off and the IQAudio as one of the 3 options (headphones HDMI / DAC)…

Neither worked but I would like to confirm the first way is the ‘correct’ way ??

volumio · December 18, 2023, 11:40am

Yes but please select the correct DAC from the DAC menu

Sefton · December 18, 2023, 11:02pm

yeah sorry I should have included that bit but yes, obviously I selected the correct DAC.

I really struggle to believe the DAC has just gone bad (while off for 3 weeks)… But, thats what it seems like…

Thats a full reset and restore from cold now… Not much else to try… Will pull the DAC and clean pins but its barely used and shiny, I just struggle to see its failure…