Volumio Version: 2.861
Hardware: raspberry pi 4, 2gb model.
DAC: khadas tone board.
problem with playback, after a while no sound usb 3 output raspberry pi 4 khadas tone board, it continues to play but no sound.
no problem with usb 2 output.
spdif input does play normally.
original usb 3 cable and other cables tried but the problem remains.
updated the khadas tone board firmware, now it only disconnects and stops, it will now continue playing when I select the khadas again in Spotify for example.
as soon as the connection with the dac is lost you will get the following.
[Vollibrespot] : Setting up new mixer: card:hw:5 mixer:Tone1 index:0
see section log!.
Starting Live Log...
info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::volumioGetQueue
info: CoreStateMachine::getQueue
info: CorePlayQueue::getQueue
info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
info: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
[Vollibrespot] : Connecting to AP "******************"
[Vollibrespot] : Authenticated as "***********" !
[Vollibrespot] : Setting up new mixer: card:hw:5 mixer:Tone1 index:0
[Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!!
[Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-12700)[dB]) -- max: 127 (MilliBel(0)[dB]) HW: true
[Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!!
[SpotifyConnect] 100
[SpotifyConnect] Volume: Spotify:100 Volumio: 100
info: CoreStateMachine::pushState
info: CorePlayQueue::getTrack 0
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Using alsa sink
[Vollibrespot] : Metadata pipe established
[Vollibrespot] : Country: "NL"
[Vollibrespot] : Event: Volume { volume_to_mixer: 65535 }
[Vollibrespot] : Fetching autoplay context uri
[Vollibrespot] : Event: SessionActive { became_active_at: 1620747928406 }
[Vollibrespot] : SessionActive!
[SpotifyConnect] A connect session has begun
[Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 84781842612720230375261977081072073254, audio_type: Track } }
[Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 84781842612720230375261977081072073254, audio_type: Track } }
[SpotifyConnect] Device palyback is active!
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
[SpotifyConnect] Currently active: webradio
[SpotifyConnect] Stopping currently active service
info: CoreCommandRouter::volumioStop
[Vollibrespot] : Loading with Spotify URI
info: CoreStateMachine::stop
info: CoreStateMachine::setConsumeUpdateService undefined
UNSET VOLATILE
[SpotifyConnect] Vollibrespot Active
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 0
[SpotifyConnect] Currently active: webradio
[SpotifyConnect] Setting Volatile state to Volspotconnect2
info: CoreStateMachine::setConsumeUpdateService undefined
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
[Vollibrespot] : No autoplay_uri found
[Vollibrespot] : AutoplayError: MercuryError
[Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCx3lR2JJv6xj3pd_XM61T2rba_ttnlB1LQuhMqBxJIHnDzedBJ-UgdojkfodeMYByeGGOFl_mOcrHJpppDEK7DzYgsN8qCFAyEPpfKCCO3GyNQaNHUOeJIqHcTGllstM8fAgqB1n1w-CRkCfM6749i_je3nOfX5KNhAIZa", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } }
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: CoreCommandRouter::volumioGetState
[SpotifyConnect] Currently active: volspotconnect2
[SpotifyConnect] Pushing new state :: true
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[SpotifyConnect] BQCx3lR2JJv6xj3pd_XM61T2rba_ttnlB1LQuhMqBxJIHnDzedBJ-UgdojkfodeMYByeGGOFl_mOcrHJpppDEK7DzYgsN8qCFAyEPpfKCCO3GyNQaNHUOeJIqHcTGllstM8fAgqB1n1w-CRkCfM6749i_je3nOfX5KNhAIZa
[SpotifyConnect] Setting VLS device_id: b29c9acf1f459114b9a515c5d1088bb14faa82df
[Vollibrespot] : (201038 ms) loaded
[SpotifyConnect] Device palyback is inactive
[SpotifyConnect] Device Session is_active: true
[Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 84781842612720230375261977081072073254, audio_type: Track } }
[Vollibrespot] : Event: Play { track_id: SpotifyId { id: 84781842612720230375261977081072073254, audio_type: Track }, position_ms: 4 }
[SpotifyConnect] play
[Vollibrespot] : Event: SinkActive
[SpotifyConnect] Penelope
[SpotifyConnect] Pushing metadata
info: CoreCommandRouter::volumioGetState
[SpotifyConnect] Currently active: volspotconnect2
[SpotifyConnect] Pushing new state :: true
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[SpotifyConnect] Sink acquired
info: CoreCommandRouter::volumioGetState
[SpotifyConnect] Currently active: volspotconnect2
[SpotifyConnect] Pushing new state :: true
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Event: Next { track_id: SpotifyId { id: 20064934123730160124159430274025423432, audio_type: Track } }
[Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 84781842612720230375261977081072073254, audio_type: Track }, track_id: SpotifyId { id: 20064934123730160124159430274025423432, audio_type: Track } }
[SpotifyConnect] Thirteen
[SpotifyConnect] Pushing metadata
info: CoreCommandRouter::volumioGetState
[SpotifyConnect] Currently active: volspotconnect2
[SpotifyConnect] Pushing new state :: true
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Event: SinkInactive
[Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 20064934123730160124159430274025423432, audio_type: Track } }
[SpotifyConnect] Sink released
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Loading with Spotify URI
info: touch_display: Setting screensaver timeout to 0 seconds.
[Vollibrespot] : (184869 ms) loaded
[Vollibrespot] : Event: SinkActive
[SpotifyConnect] Sink acquired
info: CoreCommandRouter::volumioGetState
[SpotifyConnect] Currently active: volspotconnect2
[SpotifyConnect] Pushing new state :: true
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
info: touch_display: Setting screensaver timeout to 0 seconds.
[Vollibrespot] : Event: Next { track_id: SpotifyId { id: 105246080475623257651896384782742726577, audio_type: Track } }
[Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 20064934123730160124159430274025423432, audio_type: Track }, track_id: SpotifyId { id: 105246080475623257651896384782742726577, audio_type: Track } }
[SpotifyConnect] Frost
[SpotifyConnect] Pushing metadata
info: CoreCommandRouter::volumioGetState
[SpotifyConnect] Currently active: volspotconnect2
[SpotifyConnect] Pushing new state :: true
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[SpotifyConnect] Sink released
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Event: SinkInactive
[Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 105246080475623257651896384782742726577, audio_type: Track } }
[Vollibrespot] : Loading with Spotify URI
info: touch_display: Setting screensaver timeout to 0 seconds.
[Vollibrespot] : (324107 ms) loaded
[Vollibrespot] : Event: SinkActive
[SpotifyConnect] Sink acquired
info: CoreCommandRouter::volumioGetState
[SpotifyConnect] Currently active: volspotconnect2
[SpotifyConnect] Pushing new state :: true
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Event: Next { track_id: SpotifyId { id: 196322758445766552688181909277329741768, audio_type: Track } }
[Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 105246080475623257651896384782742726577, audio_type: Track }, track_id: SpotifyId { id: 196322758445766552688181909277329741768, audio_type: Track } }
[SpotifyConnect] Follow You
[SpotifyConnect] Pushing metadata
info: CoreCommandRouter::volumioGetState
[SpotifyConnect] Currently active: volspotconnect2
[SpotifyConnect] Pushing new state :: true
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Event: SinkInactive
[Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 196322758445766552688181909277329741768, audio_type: Track } }
[SpotifyConnect] Sink released
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Loading with Spotify URI
info: touch_display: Setting screensaver timeout to 0 seconds.
[Vollibrespot] : (192156 ms) loaded
[Vollibrespot] : Event: SinkActive
[SpotifyConnect] Sink acquired
info: CoreCommandRouter::volumioGetState
[SpotifyConnect] Currently active: volspotconnect2
[SpotifyConnect] Pushing new state :: true
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Event: Next { track_id: SpotifyId { id: 263284360199753733096833996249869609211, audio_type: Track } }
[Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 196322758445766552688181909277329741768, audio_type: Track }, track_id: SpotifyId { id: 263284360199753733096833996249869609211, audio_type: Track } }
[SpotifyConnect] Synapsis
[SpotifyConnect] Pushing metadata
info: CoreCommandRouter::volumioGetState
[SpotifyConnect] Currently active: volspotconnect2
[SpotifyConnect] Pushing new state :: true
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Event: SinkInactive
[SpotifyConnect] Sink released
info: CoreCommandRouter::servicePushState
info: CoreStateMachine::pushState
[Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 263284360199753733096833996249869609211, audio_type: Track } }
info: CoreCommandRouter::volumioPushState
info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
[Vollibrespot] : Loading with Spotify URI
info: touch_display: Setting screensaver timeout to 0 seconds.
[VollibrespotNo issues here with a Khadas TB connected to a PI4 on a usb 3.0 port.
You said you updated the firmware, but I did not get exactly what the difference is now.
Would you share a full log please?
Ideally from a fresh reboot, after reproduction of the problem.
Please limit to webradio for the moment so I can compare logs easier (I don’t use Spotify here).
the difference after the update is that after reselecting the dac with for example spotify it plays normally again and for the update I first had to turn off the raspberry pi and reconnect the usb c and after restarting it played again for a while .
I will drop the full log here this afternoon (as soon as the problem is present)
here’s the log after it stopped, i only used web radio.
as soon as I stop the web radio (as soon as the problem is there) and choose another web radio it plays normally again for a while.
update! have now used another usb c and so far (about an hour) had no problem.
Well, that could have been the issue. From your log I can see that the usb connection to the DAC disconnects and is being detached (timestamp May 12 10:45:33) .
Whenever you try playing something, Volumio will try to reattach, which appears to be working.
So, cable could have been the issue, my Khadas cables already have wacky contacts, first ones (the early grey/black ones) were thrown out already.
I had already seen it in the log, it could just be that the usb cable is bad, I now use a usb c cable that has never been used.
hopefully this has been the problem.
I will let you know as soon as I have problems.
playing music all day, no problem at all, not even with Spotify.
so we can now conclude that the usb-c cables were broken.
dan was het de moeite waard 
Haha Nederlands 
was zeker de moeite waard, trouwens de khadas tone board is een fijn dacje speelt heel mooi.
Thanks for the help 