-- Logs begin at Sat 2025-12-20 04:27:12 CET, end at Mon 2026-10-12 19:53:58 CEST. --
Oct 12 19:52:08 volumio-schlafzimmer systemd-timedated[766]: Changed local time to Mon Oct 12 19:52:08 2026
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Scanning category music_service
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Scanning category plugins.json
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Scanning category system_controller
Oct 12 19:52:08 volumio-schlafzimmer sudo[763]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:08 volumio-schlafzimmer volumio-time-update[469]: volumio-time-update-util: System time updated successfully.
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Scanning category user_interface
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Scanning into folder /data/plugins/
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Scanning category audio_interface
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Scanning category music_service
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Scanning category system_controller
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Scanning category user_interface
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Plugin folders cleanup completed
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: -------------------------------------------
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: -----      Core plugins startup        ----
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: -------------------------------------------
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Loading plugins from folder /volumio/app/plugins/
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Adding plugin upnp to MyMusic Plugins
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Adding plugin airplay_emulation to MyMusic Plugins
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Adding plugin upnp_browser to MyMusic Plugins
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Loading plugins from folder /data/plugins/
Oct 12 19:52:08 volumio-schlafzimmer systemd[1]: Starting Daily man-db regeneration...
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Loading plugin "system"...
Oct 12 19:52:08 volumio-schlafzimmer systemd[1]: Starting Daily apt download activities...
Oct 12 19:52:08 volumio-schlafzimmer systemd[1]: Started Volumio Time Update Utility.
Oct 12 19:52:08 volumio-schlafzimmer systemd[1]: Reached target Multi-User System.
Oct 12 19:52:08 volumio-schlafzimmer systemd[1]: Reached target Graphical Interface.
Oct 12 19:52:08 volumio-schlafzimmer systemd[1]: Starting Update UTMP about System Runlevel Changes...
Oct 12 19:52:08 volumio-schlafzimmer volumio[668]: info: Loading plugin "appearance"...
Oct 12 19:52:08 volumio-schlafzimmer systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Oct 12 19:52:08 volumio-schlafzimmer systemd[1]: Started Update UTMP about System Runlevel Changes.
Oct 12 19:52:08 volumio-schlafzimmer systemd[1]: man-db.service: Succeeded.
Oct 12 19:52:08 volumio-schlafzimmer systemd[1]: Started Daily man-db regeneration.
Oct 12 19:52:09 volumio-schlafzimmer volumio-remote-updater[468]: [2026-10-12 19:52:09] [connect] Successful connection
Oct 12 19:52:09 volumio-schlafzimmer systemd[1]: apt-daily.service: Succeeded.
Oct 12 19:52:10 volumio-schlafzimmer systemd[1]: Started Daily apt download activities.
Oct 12 19:52:10 volumio-schlafzimmer systemd[1]: Starting Daily apt upgrade and clean activities...
Oct 12 19:52:11 volumio-schlafzimmer systemd[1]: apt-daily-upgrade.service: Succeeded.
Oct 12 19:52:11 volumio-schlafzimmer systemd[1]: Started Daily apt upgrade and clean activities.
Oct 12 19:52:11 volumio-schlafzimmer systemd[1]: Startup finished in 15.114s (kernel) + 23.280s (userspace) = 38.395s.
Oct 12 19:52:11 volumio-schlafzimmer volumio[668]: info: Loading plugin "network"...
Oct 12 19:52:11 volumio-schlafzimmer volumio[668]: info: Refreshing Cached IP Addresses
Oct 12 19:52:11 volumio-schlafzimmer sudo[876]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 12 19:52:11 volumio-schlafzimmer sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:11 volumio-schlafzimmer sudo[878]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 12 19:52:11 volumio-schlafzimmer sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:11 volumio-schlafzimmer sudo[876]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:11 volumio-schlafzimmer volumio[668]: info: Loading plugin "services"...
Oct 12 19:52:11 volumio-schlafzimmer volumio[668]: info: Loading plugin "alsa_controller"...
Oct 12 19:52:11 volumio-schlafzimmer sudo[878]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:11 volumio-schlafzimmer sudo[887]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Oct 12 19:52:11 volumio-schlafzimmer sudo[887]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:11 volumio-schlafzimmer sudo[887]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: Loading plugin "wizard"...
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: Loading plugin "networkfs"...
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: Starting Udev Watcher for removable devices
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: Ignoring mount for partition: boot
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: Ignoring mount for partition: volumio
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: Ignoring mount for partition: volumio_data
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: Loading plugin "volumio_command_line_client"...
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: Plugin upnp is not enabled
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: Loading plugin "my_music"...
Oct 12 19:52:12 volumio-schlafzimmer volumio[668]: info: Loading plugin "mpd"...
Oct 12 19:52:13 volumio-schlafzimmer volumio[668]: info: Loading plugin "upnp_browser"...
Oct 12 19:52:13 volumio-schlafzimmer volumio[668]: info: Starting UPNP Browser
Oct 12 19:52:13 volumio-schlafzimmer volumio[668]: info: Loading plugin "alarm-clock"...
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: info: Loading plugin "airplay_emulation"...
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: info: Starting Shairport Sync
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: info: Loading plugin "last_100"...
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: info: Loading plugin "webradio"...
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: info: Loading plugin "i2s_dacs"...
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: info: Loading plugin "volumiodiscovery"...
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 12 19:52:14 volumio-schlafzimmer node[668]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: *** WARNING *** For more information see 
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: *** WARNING *** For more information see 
Oct 12 19:52:14 volumio-schlafzimmer node[668]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 12 19:52:14 volumio-schlafzimmer node[668]: *** WARNING *** For more information see 
Oct 12 19:52:14 volumio-schlafzimmer node[668]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 12 19:52:14 volumio-schlafzimmer node[668]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 12 19:52:14 volumio-schlafzimmer node[668]: *** WARNING *** For more information see 
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: info: Applying required configuration parameters for plugin volumiodiscovery
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: info: Discovery: Started advertising with name: Volumio Schlafzimmer
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 12 19:52:14 volumio-schlafzimmer volumio[668]: info: Loading plugin "spop"...
Oct 12 19:52:17 volumio-schlafzimmer volumio[668]: info: Loading plugin "ytmusic"...
Oct 12 19:52:23 volumio-schlafzimmer systemd[1]: systemd-fsckd.service: Succeeded.
Oct 12 19:52:24 volumio-schlafzimmer volumio-remote-updater[468]: [2026-10-12 19:52:24] [connect] Successful connection
Oct 12 19:52:25 volumio-schlafzimmer volumio[668]: info: Loading plugin "autostart"...
Oct 12 19:52:25 volumio-schlafzimmer volumio[668]: info: Applying required configuration parameters for plugin autostart
Oct 12 19:52:25 volumio-schlafzimmer volumio[668]: info: AutoStart - onVolumioStart -  read config.json
Oct 12 19:52:25 volumio-schlafzimmer volumio[668]: info: Loading plugin "outputs"...
Oct 12 19:52:25 volumio-schlafzimmer volumio[668]: info: Loading plugin "albumart"...
Oct 12 19:52:25 volumio-schlafzimmer volumio[668]: info: Plugin example_plugin is not enabled
Oct 12 19:52:25 volumio-schlafzimmer volumio[668]: info: Loading plugin "inputs"...
Oct 12 19:52:26 volumio-schlafzimmer volumio[668]: info: Loading plugin "updater_comm"...
Oct 12 19:52:26 volumio-schlafzimmer volumio[668]: info: Plugin mpdemulation is not enabled
Oct 12 19:52:26 volumio-schlafzimmer volumio[668]: info: Loading plugin "rest_api"...
Oct 12 19:52:26 volumio-schlafzimmer volumio[668]: info: Loading plugin "websocket"...
Oct 12 19:52:26 volumio-schlafzimmer volumio[668]: info: Starting Socket.io Server version 2.3.0
Oct 12 19:52:26 volumio-schlafzimmer volumio[668]: info: Loading plugin "fusiondsp"...
Oct 12 19:52:26 volumio-schlafzimmer volumio[668]: Forking 3 albumart workers
Oct 12 19:52:27 volumio-schlafzimmer volumio[668]: info: Applying required configuration parameters for plugin fusiondsp
Oct 12 19:52:27 volumio-schlafzimmer volumio[668]: info: Loading plugin "podcast"...
Oct 12 19:52:28 volumio-schlafzimmer volumio[668]: info: ControllerPodcast::constructor
Oct 12 19:52:28 volumio-schlafzimmer volumio[668]: Starting albumart workers
Oct 12 19:52:28 volumio-schlafzimmer volumio[668]: Starting albumart workers
Oct 12 19:52:28 volumio-schlafzimmer volumio[668]: Starting albumart workers
Oct 12 19:52:28 volumio-schlafzimmer volumio[668]: info: Loading plugin "touch_display"...
Oct 12 19:52:29 volumio-schlafzimmer volumio[668]: info: Applying required configuration parameters for plugin touch_display
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: Loading i18n strings for locale de
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: Updating browse sources language
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::initPlayerControls
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: Express server listening on port 3000
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]:  [Metrics]  WebUI:  27s 690.79ms 
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::resetVolumioState
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::getcurrentVolume
Oct 12 19:52:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioRetrievevolume
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: Volumio Network Manager: Network status updated: 1
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: wlan0     Interface doesn't support scanning.
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: Cannot use regular scanning, forcing with ap-force
Oct 12 19:52:31 volumio-schlafzimmer sudo[980]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Oct 12 19:52:31 volumio-schlafzimmer sudo[980]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:31 volumio-schlafzimmer sudo[980]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: command failed: No such device (-19)
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: command failed: No such device (-19)
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 1
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.33 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 2
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.33 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 3
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 4
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.33 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 5
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.33 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 6
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 7
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: Reloading queue from file
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::setRepeat null single undefined
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::pushState
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioPushState
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::setRandom null
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::pushState
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioPushState
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: Setting Device type: Raspberry PI
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: Completed loading Core Plugins
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: Preparing to generate the ALSA configuration file
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: VolumeController:: Volume=51 Mute =false
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::pushState
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioPushState
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::updateTrackBlock
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrackBlock
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioRetrievevolume
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: info: Reading ALSA contributions from plugins.
Oct 12 19:52:31 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 9
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Discovery: adding f24f6a5e-87da-48eb-ab91-67938290724f
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Discovery: Found device Volumio Schlafzimmer
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Received Get System Info
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Discovery: Getting this device information
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Listing playlists
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: VolumeController:: Volume=51 Mute =false
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::pushState
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioPushState
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Asound.conf file unchanged, so no further update is needed
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Output device has changed, restarting MPD
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Output device has changed, restarting Shairport Sync
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:32 volumio-schlafzimmer sudo[1007]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 12 19:52:32 volumio-schlafzimmer sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:32 volumio-schlafzimmer sudo[1007]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:32 volumio-schlafzimmer sudo[1009]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 12 19:52:32 volumio-schlafzimmer sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: ___________ START PLUGINS ___________
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: ControllerMpd::onStart: Initializing MPD
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Creating MPD Configuration file
Oct 12 19:52:32 volumio-schlafzimmer systemd[1]: Listening on mpd.socket.
Oct 12 19:52:32 volumio-schlafzimmer systemd[1]: Starting Music Player Daemon...
Oct 12 19:52:32 volumio-schlafzimmer sudo[1017]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 12 19:52:32 volumio-schlafzimmer sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:32 volumio-schlafzimmer sudo[1017]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: [1791827552787] CoreMusicLibrary::Adding element Medienserver
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:52:32 volumio-schlafzimmer sudo[1020]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 12 19:52:32 volumio-schlafzimmer sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: UPNP Browser: Client initialized successfully
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:32 volumio-schlafzimmer systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Oct 12 19:52:32 volumio-schlafzimmer systemd[1]: mpd.service: Succeeded.
Oct 12 19:52:32 volumio-schlafzimmer systemd[1]: Stopped Music Player Daemon.
Oct 12 19:52:32 volumio-schlafzimmer systemd[1]: Starting Music Player Daemon...
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:32 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: [1791827553048] CoreMusicLibrary::Adding element Last_100
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: [1791827553055] CoreMusicLibrary::Adding element Webradio
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: Initializing BBC Radios
Oct 12 19:52:33 volumio-schlafzimmer sudo[1024]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 12 19:52:33 volumio-schlafzimmer sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:33 volumio-schlafzimmer sudo[1024]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Oct 12 19:52:33 volumio-schlafzimmer sudo[1024]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: Creating Spotify config file
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: [1791827553412] CoreMusicLibrary::Adding element YouTube Music
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: Cannot find translation for source YouTube Music
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: AutoStart - onStart
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: Loading i18n strings for locale de
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: FusionDsp -  mixtype--------------------- Hardware
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: [1791827553490] CoreMusicLibrary::Adding element Podcast
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: Cannot find translation for source YouTube Music
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: Cannot find translation for source Podcast
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: Loading i18n strings for locale de
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: Volumio Calling Home
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: Preparing to generate the ALSA configuration file
Oct 12 19:52:33 volumio-schlafzimmer sudo[1046]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Oct 12 19:52:33 volumio-schlafzimmer sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:33 volumio-schlafzimmer systemd[1]: Reloading.
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: Reading ALSA contributions from plugins.
Oct 12 19:52:33 volumio-schlafzimmer volumio[668]: info: FusionDsp -
Oct 12 19:52:34 volumio-schlafzimmer volumio[668]: info: FusionDsp -
Oct 12 19:52:34 volumio-schlafzimmer systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
Oct 12 19:52:35 volumio-schlafzimmer systemd[1]: serial-getty@ttyAMA0.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Oct 12 19:52:35 volumio-schlafzimmer volumio[668]: info: MPD Permissions set
Oct 12 19:52:35 volumio-schlafzimmer volumio[668]: info: MPD Permissions set
Oct 12 19:52:35 volumio-schlafzimmer volumio[668]: info: FusionDsp - undefined
Oct 12 19:52:35 volumio-schlafzimmer systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Oct 12 19:52:35 volumio-schlafzimmer volumio[668]: info: FusionDsp - undefined
Oct 12 19:52:35 volumio-schlafzimmer sudo[1046]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: Volumio called home
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: camilladsp service started and running in background, instance 1
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: FusionDsp -  AAAAAAAAAAAAAA->  22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: FusionDsp loaded
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 12 19:52:36 volumio-schlafzimmer sudo[1073]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Oct 12 19:52:36 volumio-schlafzimmer sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: FusionDsp -  Reporting Fusion DSP Enabled
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: Adding Signal Path Element [object Object]
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: Adding fusiondspeq DSP Signal Path Element
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: FusionDsp -  ---- installed callbackRead
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: FusionDsp -  If filter freq >samplerate/2 then disable it
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: Spotify config file written
Oct 12 19:52:36 volumio-schlafzimmer systemd[1]: Started FusionDsp Daemon.
Oct 12 19:52:36 volumio-schlafzimmer sudo[1081]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Oct 12 19:52:36 volumio-schlafzimmer sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:36 volumio-schlafzimmer sudo[1073]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:36 volumio-schlafzimmer go-librespot[1084]: go-librespot daemon starting...
Oct 12 19:52:36 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:52:36 volumio-schlafzimmer sudo[1081]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: touch_display: systemctl daemon-reload succeeded.
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:36 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:37 volumio-schlafzimmer sudo[1087]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Oct 12 19:52:37 volumio-schlafzimmer sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:37 volumio-schlafzimmer systemd[1]: Started Volumio Kiosk.
Oct 12 19:52:37 volumio-schlafzimmer sudo[1087]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: No need to fix Spotify hosts
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: touch_display: No Raspberry Pi Foundation touch screen detected.
Oct 12 19:52:37 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:37+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:52:37 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:37+02:00" level=debug msg="app state loaded"
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: touch_display: systemctl start volumio-kiosk.service succeeded.
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: touch_display: Volumio Kiosk started
Oct 12 19:52:37 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:37+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: X.Org X Server 1.20.4
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: X Protocol Version 11, Revision 0
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: Current Operating System: Linux volumio-schlafzimmer 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: Kernel command line: coherent_pool=1M snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA0,115200 console=tty1 imgpart=UUID=af3faec2-9654-4fec-8791-d5638a5837ad imgfile=/volumio_current.sqsh bootpart=UUID=2E0A-AE55 datapart=UUID=660717eb-e471-4727-aac1-2c263938c365 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: Build Date: 04 April 2023  07:50:56AM
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support)
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: Current version of pixman: 0.36.0
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]:         Before reporting problems, check http://wiki.x.org
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]:         to make sure that you have the latest version.
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: Markers: (--) probed, (**) from config file, (==) default setting,
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]:         (++) from command line, (!!) notice, (II) informational,
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]:         (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Oct 12 19:52:37 2026
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: (==) Using config directory: "/etc/X11/xorg.conf.d"
Oct 12 19:52:37 volumio-schlafzimmer startx[1098]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
Oct 12 19:52:37 volumio-schlafzimmer volumio[668]: info: An error occurred while refreshing Spotify Token Error: Bad Request
Oct 12 19:52:38 volumio-schlafzimmer systemd[1]: systemd-timedated.service: Succeeded.
Oct 12 19:52:38 volumio-schlafzimmer volumio[668]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Oct 12 19:52:38 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:52:38 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:38 volumio-schlafzimmer volumio[668]: info: Starting Shairport Sync
Oct 12 19:52:38 volumio-schlafzimmer volumio[668]: info: Starting Shairport Sync
Oct 12 19:52:38 volumio-schlafzimmer volumio[668]: info: Starting Shairport Sync
Oct 12 19:52:38 volumio-schlafzimmer sudo[1135]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 12 19:52:38 volumio-schlafzimmer sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:38 volumio-schlafzimmer sudo[1137]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 12 19:52:38 volumio-schlafzimmer sudo[1139]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 12 19:52:38 volumio-schlafzimmer sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:38 volumio-schlafzimmer sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:38 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:52:38 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:38 volumio-schlafzimmer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 12 19:52:38 volumio-schlafzimmer systemd[1]: shairport-sync.service: Succeeded.
Oct 12 19:52:38 volumio-schlafzimmer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 12 19:52:38 volumio-schlafzimmer volumio[668]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Oct 12 19:52:39 volumio-schlafzimmer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 12 19:52:39 volumio-schlafzimmer sudo[1135]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:39 volumio-schlafzimmer sudo[1139]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:39 volumio-schlafzimmer sudo[1137]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: Asound.conf file unchanged, so no further update is needed
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: Output device has changed, restarting MPD
Oct 12 19:52:39 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:52:39 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:39+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:52:39 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:39+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:52:39 volumio-schlafzimmer volumio-remote-updater[468]: [2026-10-12 19:52:39] [connect] Successful connection
Oct 12 19:52:39 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:39+02:00" level=info msg="zeroconf server listening on port 45123"
Oct 12 19:52:39 volumio-schlafzimmer sudo[1149]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: Output device has changed, restarting Shairport Sync
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:39 volumio-schlafzimmer sudo[1149]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:39 volumio-schlafzimmer sudo[1152]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 12 19:52:39 volumio-schlafzimmer sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:39 volumio-schlafzimmer systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Oct 12 19:52:39 volumio-schlafzimmer systemd[1]: mpd.service: Succeeded.
Oct 12 19:52:39 volumio-schlafzimmer systemd[1]: Stopped Music Player Daemon.
Oct 12 19:52:39 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:39+02:00" level=debug msg="obtained new client token: AAER5LJZ1jYL49xyXgnE2q4UZw0yj7dmrfS621CfsqXHUSUFE39jDFTyueSMX+OxVxDipa26AU5h5p54wEWVP/1UgiRgtR/HFWSgdG9Z5tGyQNJuzlQstutvZVlPAbgJdBdj3IxnAQIvVcuFnXw1vFiszs/6Jho5S8sYe6uu6nVEZCN7hdi62sr/7HqFvcf3v4W5cPNAWIQWtkMrubeb5zWAldU7BAeCT1nIQbnv4yV5yyKNLghMKal4kA=="
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 12 19:52:39 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:52:39 volumio-schlafzimmer systemd[1]: Starting Music Player Daemon...
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: Shairport-Sync Started
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: Error adding Membership: Error: addMembership EINVAL
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: Shairport-Sync Started
Oct 12 19:52:39 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:39+02:00" level=debug msg="completed keyexchange"
Oct 12 19:52:39 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:39+02:00" level=debug msg="completed challenge"
Oct 12 19:52:39 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:39+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: MPD Permissions set
Oct 12 19:52:39 volumio-schlafzimmer volumio-remote-updater[468]: [2026-10-12 19:52:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1791827559 101
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 12
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:52:39 volumio-schlafzimmer go-librespot[1084]: time="2026-10-12T19:52:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:52:39 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:52:39 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:52:39 volumio-schlafzimmer sudo[1157]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 12 19:52:40 volumio-schlafzimmer sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:40 volumio-schlafzimmer volumio[668]: info: Starting Shairport Sync
Oct 12 19:52:40 volumio-schlafzimmer sudo[1157]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Oct 12 19:52:40 volumio-schlafzimmer sudo[1157]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:40 volumio-schlafzimmer sudo[1167]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 12 19:52:40 volumio-schlafzimmer sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:40 volumio-schlafzimmer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 12 19:52:40 volumio-schlafzimmer systemd[1]: shairport-sync.service: Succeeded.
Oct 12 19:52:40 volumio-schlafzimmer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 12 19:52:40 volumio-schlafzimmer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 12 19:52:40 volumio-schlafzimmer sudo[1167]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:40 volumio-schlafzimmer volumio[668]: info: Shairport-Sync Started
Oct 12 19:52:40 volumio-schlafzimmer volumio[668]: info: go-librespot daemon successfully initialized
Oct 12 19:52:41 volumio-schlafzimmer volumio[668]: info: touch_display: Setting screensaver timeout to 120 seconds.
Oct 12 19:52:43 volumio-schlafzimmer mpd[1166]: Oct 12 19:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 12 19:52:43 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:52:43 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Oct 12 19:52:43 volumio-schlafzimmer systemd[1]: Started Music Player Daemon.
Oct 12 19:52:43 volumio-schlafzimmer sudo[1020]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:43 volumio-schlafzimmer sudo[1152]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:43 volumio-schlafzimmer sudo[1009]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:43 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:52:43 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:52:43 volumio-schlafzimmer go-librespot[1243]: go-librespot daemon starting...
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: info: Completed starting Core Plugins
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: info: -------------------------------------------
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: info: -----    MyVolumio plugins startup     ----
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: info: -------------------------------------------
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Fetching plans data....
Oct 12 19:52:43 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:43+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:52:43 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:43+02:00" level=debug msg="app state loaded"
Oct 12 19:52:43 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:43+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: error: MPD error: The expression evaluated to a falsy value:
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]:   assert.ok(self.idling)
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: error: The expression evaluated to a falsy value:
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]:   assert.ok(self.idling)
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: error: MPD error: The expression evaluated to a falsy value:
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]:   assert.ok(self.idling)
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: error: The expression evaluated to a falsy value:
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]:   assert.ok(self.idling)
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: error: updateQueue error: null
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: info: MPD running with PID1166
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]:  ,establishing connection
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: error: updateQueue error: null
Oct 12 19:52:43 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:43+02:00" level=debug msg="new websocket client"
Oct 12 19:52:43 volumio-schlafzimmer volumio[668]: info: Connection to go-librespot Websocket established
Oct 12 19:52:44 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:52:44 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:44+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:52:44 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:44+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:52:44 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:44+02:00" level=info msg="zeroconf server listening on port 41355"
Oct 12 19:52:44 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:44+02:00" level=debug msg="obtained new client token: AAHdjQrlh2Xaus7QiYTABGLUJT0kNhs/JpIk6uNeC4Us1ZSDhPIaq74kkkdyF112St6edU7m/iFM6QGCbXzyDqmQ8+W49RJCO/EAl0kLtP2fFxIi45udDoN2/MnLcWIc91XNr3YIzOdHk5D401zDqcc5AFWbD8NzwfQDnsgUVFHQ6VtvPDPeQZStrhaNJ54zLFAEQZi0iBxcGa0YMgw7QCLsshrrN2PhMbj4mVccnvUlerW1bVal7o0="
Oct 12 19:52:44 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:44+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:52:44 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:44+02:00" level=debug msg="completed keyexchange"
Oct 12 19:52:44 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:44+02:00" level=debug msg="completed challenge"
Oct 12 19:52:44 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:44+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:52:44 volumio-schlafzimmer go-librespot[1243]: time="2026-10-12T19:52:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:52:44 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:52:44 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:52:44 volumio-schlafzimmer volumio[668]: info: Connection to go-librespot Websocket closed
Oct 12 19:52:46 volumio-schlafzimmer sudo[1278]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Oct 12 19:52:46 volumio-schlafzimmer sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:46 volumio-schlafzimmer sudo[1280]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Oct 12 19:52:46 volumio-schlafzimmer sudo[1283]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Oct 12 19:52:46 volumio-schlafzimmer sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:46 volumio-schlafzimmer sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:52:46 volumio-schlafzimmer systemd[1]: Started MPD Monitor Service.
Oct 12 19:52:46 volumio-schlafzimmer mpd_monitor.sh[1286]: MPD Monitor Service: Starting MPD Monitor Service
Oct 12 19:52:46 volumio-schlafzimmer sudo[1278]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: info: Successfully started MPD Monitor
Oct 12 19:52:46 volumio-schlafzimmer systemd[1]: Stopping MPD Monitor Service...
Oct 12 19:52:46 volumio-schlafzimmer systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM
Oct 12 19:52:46 volumio-schlafzimmer systemd[1]: mpd_monitor.service: Succeeded.
Oct 12 19:52:46 volumio-schlafzimmer systemd[1]: Stopped MPD Monitor Service.
Oct 12 19:52:46 volumio-schlafzimmer systemd[1]: Started MPD Monitor Service.
Oct 12 19:52:46 volumio-schlafzimmer sudo[1283]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:46 volumio-schlafzimmer sudo[1280]: pam_unix(sudo:session): session closed for user root
Oct 12 19:52:46 volumio-schlafzimmer mpd_monitor.sh[1289]: MPD Monitor Service: Starting MPD Monitor Service
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: info: Successfully started MPD Monitor
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: info: Successfully started MPD Monitor
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: info: Getting Spotify volume
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: (node:668) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]:     at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: (Use `node --trace-warnings ...` to show where the warning was created)
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: (node:668) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1)
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: (node:668) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code.
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: SPOTIFY: SPOTIFY VOLUME undefined
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: SPOTIFY: VOLUMIO VOLUME 51
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: info: Aligning Spotify Volume to Volumio Volume
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:52:46 volumio-schlafzimmer volumio[668]: info: Setting Spotify Volume from Volumio: 51
Oct 12 19:52:47 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:52:47 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:52:47 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:52:47 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Oct 12 19:52:47 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:52:47 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:52:47 volumio-schlafzimmer go-librespot[1303]: go-librespot daemon starting...
Oct 12 19:52:47 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:47+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:52:47 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:47+02:00" level=debug msg="app state loaded"
Oct 12 19:52:47 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:47+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:52:47 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:47+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:52:47 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:47+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:52:47 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:47+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:52:48 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:48+02:00" level=info msg="zeroconf server listening on port 44667"
Oct 12 19:52:48 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:48+02:00" level=debug msg="obtained new client token: AAHZK0GvDSQt6SRXWeF+pCCZ2gTn6gyBZo6hcLHKDDxchLoTZqjR5KPIJe0AGcrrAgBMm5yKf3b4mTUiOtmduRzvd/njiKRtkCJzefQXuEDf1+fU1IO+lGHW0Fuhug4DlckmRkpDpyK5WH5glMA0R1zQe+edhJSgIPlQdnSMTaKQ45znOzn9yiTHRniOV6o9ZZGhE41rMJ4IXvX+hmlhLnN4utqN3H7Exn5ybJeNdwVjGXD1Rg1ghAs="
Oct 12 19:52:48 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:52:48 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:48+02:00" level=debug msg="completed keyexchange"
Oct 12 19:52:48 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:48+02:00" level=debug msg="completed challenge"
Oct 12 19:52:48 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:48+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:52:48 volumio-schlafzimmer volumio[668]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Oct 12 19:52:48 volumio-schlafzimmer volumio[668]: SPOTIFY: SETTING SPOTIFY VOLUME 51
Oct 12 19:52:48 volumio-schlafzimmer volumio[668]: info: Sending Spotify command with payload to local API: /player/volume
Oct 12 19:52:48 volumio-schlafzimmer go-librespot[1303]: time="2026-10-12T19:52:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:52:48 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:52:48 volumio-schlafzimmer volumio[668]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up
Oct 12 19:52:48 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:52:50 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:52:50 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:52:51 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:52:51 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Oct 12 19:52:51 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:52:51 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:52:51 volumio-schlafzimmer go-librespot[1333]: go-librespot daemon starting...
Oct 12 19:52:51 volumio-schlafzimmer dbus-daemon[486]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.16' (uid=1000 pid=1231 comm="/usr/lib/chromium-browser/chromium-browser-v7 --en")
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Oct 12 19:52:51 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:51+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:52:51 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:51+02:00" level=debug msg="app state loaded"
Oct 12 19:52:51 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:51+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: Adding plugin bluetooth to MyMusic Plugins
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: Adding plugin multiroom to MyMusic Plugins
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: Adding plugin metavolumio to MyMusic Plugins
Oct 12 19:52:51 volumio-schlafzimmer volumio[668]: info: Adding plugin cd_controller to MyMusic Plugins
Oct 12 19:52:51 volumio-schlafzimmer systemd[1]: Starting Bluetooth service...
Oct 12 19:52:52 volumio-schlafzimmer volumio[668]: info: Adding plugin qobuzconnect to MyMusic Plugins
Oct 12 19:52:52 volumio-schlafzimmer volumio[668]: info: Adding plugin smart_inputs to MyMusic Plugins
Oct 12 19:52:52 volumio-schlafzimmer volumio[668]: info: Adding plugin tidalconnect to MyMusic Plugins
Oct 12 19:52:52 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Oct 12 19:52:52 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:52+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:52:52 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:52+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:52:52 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:52+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:52:52 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:52+02:00" level=info msg="zeroconf server listening on port 46791"
Oct 12 19:52:52 volumio-schlafzimmer kernel: Bluetooth: Core ver 2.22
Oct 12 19:52:52 volumio-schlafzimmer kernel: NET: Registered PF_BLUETOOTH protocol family
Oct 12 19:52:52 volumio-schlafzimmer kernel: Bluetooth: HCI device and connection manager initialized
Oct 12 19:52:52 volumio-schlafzimmer kernel: Bluetooth: HCI socket layer initialized
Oct 12 19:52:52 volumio-schlafzimmer kernel: Bluetooth: L2CAP socket layer initialized
Oct 12 19:52:52 volumio-schlafzimmer kernel: Bluetooth: SCO socket layer initialized
Oct 12 19:52:52 volumio-schlafzimmer bluetoothd[1342]: Bluetooth daemon 5.83
Oct 12 19:52:52 volumio-schlafzimmer bluetoothd[1342]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Oct 12 19:52:52 volumio-schlafzimmer dbus-daemon[486]: [system] Successfully activated service 'org.bluez'
Oct 12 19:52:52 volumio-schlafzimmer systemd[1]: Started Bluetooth service.
Oct 12 19:52:52 volumio-schlafzimmer bluetoothd[1342]: Starting SDP server
Oct 12 19:52:52 volumio-schlafzimmer bluetoothd[1342]: Excluding (cli) hostname
Oct 12 19:52:52 volumio-schlafzimmer bluetoothd[1342]: Excluding (cli) scanparam
Oct 12 19:52:52 volumio-schlafzimmer bluetoothd[1342]: Excluding (cli) battery
Oct 12 19:52:52 volumio-schlafzimmer kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Oct 12 19:52:52 volumio-schlafzimmer kernel: Bluetooth: BNEP filters: protocol multicast
Oct 12 19:52:52 volumio-schlafzimmer kernel: Bluetooth: BNEP socket layer initialized
Oct 12 19:52:52 volumio-schlafzimmer bluetoothd[1342]: Bluetooth management interface 1.22 initialized
Oct 12 19:52:52 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:52+02:00" level=debug msg="obtained new client token: AAG1wpb5GTiKlD14SJKKQcKpwhWXxKtomtQX7brAoQXoH6dyq1Vni9B4scMy8NrGahdnSmVO7R5vYYzz/TssVSeBiGZ5xekeoqImtLtH7WDDoBx2l2AH2j8dUtBe4MF00fwgyXBmTJkCXQ6Q015oIv8CKxC5/40mM4JYaliuWjHrJHcC99teHk2gxqPV6olcCq94t9uE8apM7prXufVV4WjnmTcf7aqnVbKILkxYehNT/7e3c+NTAH9HTw=="
Oct 12 19:52:52 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:52+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:52:52 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:52+02:00" level=debug msg="completed keyexchange"
Oct 12 19:52:52 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:52+02:00" level=debug msg="completed challenge"
Oct 12 19:52:52 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:52+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:52:52 volumio-schlafzimmer go-librespot[1333]: time="2026-10-12T19:52:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:52:53 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:52:53 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:52:56 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:52:56 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Oct 12 19:52:56 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:52:56 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Oct 12 19:52:56 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Oct 12 19:52:56 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:56 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:52:56 volumio-schlafzimmer volumio[668]: info: Starting MyVolumio Remote Streaming Endpoints
Oct 12 19:52:56 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:52:56 volumio-schlafzimmer go-librespot[1432]: go-librespot daemon starting...
Oct 12 19:52:56 volumio-schlafzimmer volumio[668]: info: MyVolumio login type: Token
Oct 12 19:52:56 volumio-schlafzimmer volumiologrotate[476]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory
Oct 12 19:52:56 volumio-schlafzimmer volumiologrotate[476]: ls: cannot access 'SCHLAFZIMMER': No such file or directory
Oct 12 19:52:56 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:56+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:52:56 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:56+02:00" level=debug msg="app state loaded"
Oct 12 19:52:56 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:56+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:52:56 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Oct 12 19:52:56 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Oct 12 19:52:57 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:57+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:52:57 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:57+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:52:57 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:57+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:52:57 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:57+02:00" level=info msg="zeroconf server listening on port 38563"
Oct 12 19:52:57 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:57+02:00" level=debug msg="obtained new client token: AAG7KX4ySXOeVs3X5XwiNSnKyR1omIFGfIYX+v3Dm1WD7cz+PnXANXPx/AycdiLqvsv53pATtueUZ2gBvKLARM/GJSn0OwwhuMqM3sX3V2kCFMzuu/EcpdeMWxUO0nM4eRo8vIabARfRspIQ250aKJPQC8mwfo4+Kwxw54CGhUQ2zKXPnu9fHV5pmJuirtMtbXWYpl3y5rpdl7XYXY6kFTMLhyXzhQhnYL55MSkbg9abD7FhP0dBjqI="
Oct 12 19:52:57 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:57+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:52:57 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:57+02:00" level=debug msg="completed keyexchange"
Oct 12 19:52:57 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:57+02:00" level=debug msg="completed challenge"
Oct 12 19:52:57 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:57+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:52:57 volumio-schlafzimmer go-librespot[1432]: time="2026-10-12T19:52:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:52:57 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:52:57 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:52:59 volumio-schlafzimmer volumio[668]: info: Starting Streaming Service Transparent Proxy
Oct 12 19:52:59 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Oct 12 19:52:59 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Oct 12 19:52:59 volumio-schlafzimmer volumio[668]: info: Streaming services startup
Oct 12 19:52:59 volumio-schlafzimmer volumio[668]: info: Starting Streaming Daemon
Oct 12 19:52:59 volumio-schlafzimmer volumio[668]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Oct 12 19:52:59 volumio-schlafzimmer sudo[1496]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Oct 12 19:52:59 volumio-schlafzimmer sudo[1496]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:00 volumio-schlafzimmer sudo[1496]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: AutoStart - Plugin is starting
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetQueue
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::getQueue
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getQueue
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: AutoStart - start playing
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: AutoStart - start playing with no specific position
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioPlay
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::play index 0
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::stop
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::play index undefined
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CoreStateMachine::startPlaybackTimer
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: [1791827580114] ControllerSpotify::clearAddPlayTrack
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: Sending Spotify command with payload to local API: /player/play
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: error: Cannot start Volumio Streaming Daemon
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:00 volumio-schlafzimmer volumio[668]: error: MyVolumio Custom Token format not valid, refreshing it
Oct 12 19:53:00 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:00 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Oct 12 19:53:00 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:00 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:00 volumio-schlafzimmer go-librespot[1503]: go-librespot daemon starting...
Oct 12 19:53:00 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:00+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:00 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:00+02:00" level=debug msg="app state loaded"
Oct 12 19:53:00 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:00+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:01 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 12 19:53:01 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Oct 12 19:53:01 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:01 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:01+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:01 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:01+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:01 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:01+02:00" level=info msg="zeroconf server listening on port 38487"
Oct 12 19:53:01 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:01+02:00" level=debug msg="obtained new client token: AAEM8dABDiQFauyuSNKJxPgaPl4EXyL+tCcdGKcL+ywi7CLan1ozm2+jjQ56H++1rKRQZKN30OT8eu7w4oaVdZQPkGYMzQSWkWvoWOKyWts3Mv6uw7y+JW/XrSRuFhcj1bVJtz6iasg0QYwb1WieB/X833OycnQTyAHCgbYxkNTjSIkU4qDfle8FKcva+upYBbXsNqc5kQa00JfL3ChPHHslomACDDXS/1eMIKqTOi436OMWB680KcMP1A=="
Oct 12 19:53:01 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:01+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused"
Oct 12 19:53:01 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:01+02:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Oct 12 19:53:01 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:01+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:01 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:01+02:00" level=debug msg="completed challenge"
Oct 12 19:53:01 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:01+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:02 volumio-schlafzimmer go-librespot[1503]: time="2026-10-12T19:53:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:02 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:02 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:02 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Oct 12 19:53:02 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Oct 12 19:53:02 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs
Oct 12 19:53:02 volumio-schlafzimmer volumio[668]: info: Received Get System Version
Oct 12 19:53:02 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:53:02 volumio-schlafzimmer volumio[668]: info: MyVolumio login type: Token
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: STREAMING PROXY: Starting server on port 3245
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: Node JS runtime: 14
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Oct 12 19:53:03 volumio-schlafzimmer ntpd[631]: Soliciting pool server 176.9.157.155
Oct 12 19:53:03 volumio-schlafzimmer volumio-remote-updater[468]: No test mode
Oct 12 19:53:03 volumio-schlafzimmer volumio-remote-updater[468]: No alpha test mode
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: MyVolumio token set successfully
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: MYVOLUMIO: Adding device
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: MYVOLUMIO: Evaluating Server
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for BLE notification issues
\nFix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\nUpdate Tidal SDK
\nUpdate Qobuz Connect SDK
\n\n","title":"Update v3.912","updateavailable":true}
Oct 12 19:53:03 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Oct 12 19:53:04 volumio-schlafzimmer ntpd[631]: Soliciting pool server 85.214.38.116
Oct 12 19:53:04 volumio-schlafzimmer ntpd[631]: Soliciting pool server 162.55.190.98
Oct 12 19:53:04 volumio-schlafzimmer volumio[668]: info: MyVolumio status changed
Oct 12 19:53:04 volumio-schlafzimmer volumio[668]: info: Streaming services startup
Oct 12 19:53:04 volumio-schlafzimmer volumio[668]: info: Starting Streaming Daemon
Oct 12 19:53:04 volumio-schlafzimmer volumio[668]: info: Removing browser output: myVolumio user plan is not superstar
Oct 12 19:53:04 volumio-schlafzimmer volumio[668]: info: Removing audio output:
Oct 12 19:53:04 volumio-schlafzimmer volumio[668]: info: Stoppping Tunnel 1
Oct 12 19:53:04 volumio-schlafzimmer sudo[1569]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Oct 12 19:53:04 volumio-schlafzimmer sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:04 volumio-schlafzimmer sudo[1571]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Oct 12 19:53:04 volumio-schlafzimmer sudo[1571]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:04 volumio-schlafzimmer sudo[1569]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:05 volumio-schlafzimmer sudo[1571]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:05 volumio-schlafzimmer volumio[668]: info: Setting Geolocation for MyVolumio to eu4
Oct 12 19:53:05 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:05 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:05 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:05 volumio-schlafzimmer volumio[668]: info: Remote SSH Stopped
Oct 12 19:53:05 volumio-schlafzimmer volumio[668]: error: Cannot start Volumio Streaming Daemon
Oct 12 19:53:05 volumio-schlafzimmer volumio[668]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Oct 12 19:53:05 volumio-schlafzimmer volumio[668]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Oct 12 19:53:05 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:05 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Oct 12 19:53:05 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:05 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:05 volumio-schlafzimmer go-librespot[1574]: go-librespot daemon starting...
Oct 12 19:53:05 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:05+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:05 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:05+02:00" level=debug msg="app state loaded"
Oct 12 19:53:05 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:05+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:05 volumio-schlafzimmer ntpd[631]: Soliciting pool server 159.69.124.127
Oct 12 19:53:05 volumio-schlafzimmer ntpd[631]: Soliciting pool server 89.163.176.12
Oct 12 19:53:05 volumio-schlafzimmer ntpd[631]: Soliciting pool server 217.144.138.234
Oct 12 19:53:05 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:05 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:05 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:05 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:05+02:00" level=info msg="zeroconf server listening on port 44539"
Oct 12 19:53:05 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:05+02:00" level=debug msg="obtained new client token: AAEnscoKQ8SFFhaUuLoLWO77JFz1P/RL/7R2QJ9gfZJM0p//Kliqlu6mrBxXIDmgOJ6qw5aOLqFtdEsZmE8UdsMS6Eo4soOLlLL2UfKp/X/yvAINOr5P+7kNmuSWqRYOtuws/z6KdJk0oYouPY3nbdDbBSFWkbuiMtyAqvpUjtl9fWT4+UzM1djaql5DzakHyDXIOsMdNBgT6OAJ3PZJMsY8Dy+fVmz82ltGvgvvgJuo60hhz1KXQFmzLA=="
Oct 12 19:53:05 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:05+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused"
Oct 12 19:53:06 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Oct 12 19:53:06 volumio-schlafzimmer volumio[668]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Oct 12 19:53:06 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:06+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:06 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:06+02:00" level=debug msg="completed challenge"
Oct 12 19:53:06 volumio-schlafzimmer volumio[668]: info: Updating MyVolumio device info
Oct 12 19:53:06 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:06 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:06 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:06 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:06+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:06 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:53:06 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:06+02:00" level=debug msg="new websocket client"
Oct 12 19:53:06 volumio-schlafzimmer volumio[668]: info: Connection to go-librespot Websocket established
Oct 12 19:53:06 volumio-schlafzimmer go-librespot[1574]: time="2026-10-12T19:53:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:06 volumio-schlafzimmer volumio[668]: info: Connection to go-librespot Websocket closed
Oct 12 19:53:06 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:06 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:06 volumio-schlafzimmer volumio[668]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Oct 12 19:53:06 volumio-schlafzimmer ntpd[631]: Soliciting pool server 212.132.108.186
Oct 12 19:53:06 volumio-schlafzimmer ntpd[631]: Soliciting pool server 31.70.111.17
Oct 12 19:53:06 volumio-schlafzimmer ntpd[631]: Soliciting pool server 78.47.118.0
Oct 12 19:53:07 volumio-schlafzimmer ntpd[631]: Soliciting pool server 185.197.135.21
Oct 12 19:53:07 volumio-schlafzimmer ntpd[631]: Soliciting pool server 5.9.7.51
Oct 12 19:53:08 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Oct 12 19:53:08 volumio-schlafzimmer ntpd[631]: Soliciting pool server 31.70.84.16
Oct 12 19:53:08 volumio-schlafzimmer ntpd[631]: Soliciting pool server 178.63.52.31
Oct 12 19:53:09 volumio-schlafzimmer volumio[668]: info: Getting Spotify volume
Oct 12 19:53:09 volumio-schlafzimmer volumio[668]: (node:668) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:09 volumio-schlafzimmer volumio[668]:     at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16)
Oct 12 19:53:09 volumio-schlafzimmer volumio[668]: (node:668) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2)
Oct 12 19:53:09 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:53:09 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:09 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Oct 12 19:53:09 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:09 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:09 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:09 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Oct 12 19:53:09 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:09 volumio-schlafzimmer volumio[668]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51
Oct 12 19:53:09 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:09 volumio-schlafzimmer go-librespot[1585]: go-librespot daemon starting...
Oct 12 19:53:09 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:09+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:09 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:09+02:00" level=debug msg="app state loaded"
Oct 12 19:53:09 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:09+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:09 volumio-schlafzimmer ntpd[631]: Soliciting pool server 128.127.67.142
Oct 12 19:53:09 volumio-schlafzimmer ntpd[631]: Soliciting pool server 2a01:7e01::f03c:94ff:fe24:44d0
Oct 12 19:53:10 volumio-schlafzimmer volumio[668]: info: MYVOLUMIO: Adding device
Oct 12 19:53:10 volumio-schlafzimmer volumio[668]: info: MYVOLUMIO: Evaluating Server
Oct 12 19:53:10 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 12 19:53:10 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:10 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:10+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:10 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:10+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:10 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:10+02:00" level=info msg="zeroconf server listening on port 42377"
Oct 12 19:53:10 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:10+02:00" level=debug msg="obtained new client token: AAEigiLIgSFubm5w9gOtwVWB9llUBFk5AZF/u6SJUuFEHChT2DVfuYgBAw5k77hrV5so9pjGFx2RbmTrYLzgYuZ7CYpm0OcSr0tuvXlLBjOonMWDnBROQVmjkS2dIUuM2/Dg/NH5bZ6iI8EmcgpZYettOk5YDuyHk624URNFV6xG4J4AD8ZM8OSj87JoliWIZC8pGPutp5SLGQkTO5WpaRQmha89fl2phH0gKszz32/CrsIdukH3cdUVxw=="
Oct 12 19:53:10 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:10 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:10+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:10 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:10+02:00" level=debug msg="completed challenge"
Oct 12 19:53:10 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:10+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:10 volumio-schlafzimmer ntpd[631]: Soliciting pool server 131.234.220.232
Oct 12 19:53:11 volumio-schlafzimmer ntpd[631]: receive: Unexpected origin timestamp 0xee779d06.b93a7540 does not match aorg 0000000000.00000000 from server@85.214.38.116 xmt 0xee779d07.a73e9d5e
Oct 12 19:53:11 volumio-schlafzimmer ntpd[631]: receive: Unexpected origin timestamp 0xee779d06.b91bc45d does not match aorg 0000000000.00000000 from server@128.127.67.142 xmt 0xee779d07.a75f53f0
Oct 12 19:53:11 volumio-schlafzimmer ntpd[631]: receive: Unexpected origin timestamp 0xee779d06.b933e2a4 does not match aorg 0000000000.00000000 from server@89.163.176.12 xmt 0xee779d07.a802ccf7
Oct 12 19:53:11 volumio-schlafzimmer ntpd[631]: receive: Unexpected origin timestamp 0xee779d06.b92f32a1 does not match aorg 0000000000.00000000 from server@217.144.138.234 xmt 0xee779d07.a82ad712
Oct 12 19:53:11 volumio-schlafzimmer go-librespot[1585]: time="2026-10-12T19:53:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:11 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:11 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:11 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Oct 12 19:53:12 volumio-schlafzimmer volumio[668]: info: Setting Geolocation for MyVolumio to eu4
Oct 12 19:53:12 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:12 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:12 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:12 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 12 19:53:12 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Oct 12 19:53:12 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:12 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:12 volumio-schlafzimmer ntpd[631]: Soliciting pool server 45.92.216.108
Oct 12 19:53:13 volumio-schlafzimmer volumio[668]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Oct 12 19:53:13 volumio-schlafzimmer volumio[668]: info: Updating MyVolumio device info
Oct 12 19:53:13 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:13 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:13 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:13 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:53:13 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:13 volumio-schlafzimmer volumio[668]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Oct 12 19:53:13 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
Oct 12 19:53:14 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:14 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Oct 12 19:53:14 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:14 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:14 volumio-schlafzimmer go-librespot[1625]: go-librespot daemon starting...
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=debug msg="app state loaded"
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=info msg="zeroconf server listening on port 43781"
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=debug msg="obtained new client token: AAGfry58C+8SGNk+0khOLUu1d+fLg1qxlk+bSJUgCZmhf/AyATtNH0dONedYYz4LgRjFlQxl4LZXX+9N7HKZrNzlhsvkRxqzcqNjmSnI2l9/laINJgOWY0XCdVR1zqUSBK8fFSSvNr1wi7IEe26PPW9ctUF/2Q7nYM9X6YRCOpwMAF44EbeNFo6QKF4vc3h5eqc7cATtuVAaPcCg0gNvwe77dcid4aSAieuXpPhE8C1Wt42SuJO1njflZw=="
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=debug msg="completed challenge"
Oct 12 19:53:15 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:15+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:16 volumio-schlafzimmer go-librespot[1625]: time="2026-10-12T19:53:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:16 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:16 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:16 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:53:16 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:17 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:17 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:17 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Oct 12 19:53:17 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Oct 12 19:53:17 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Oct 12 19:53:17 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Oct 12 19:53:17 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetBrowseSources
Oct 12 19:53:17 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:53:18 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10
Oct 12 19:53:18 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:18 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:19 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:53:19 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:19 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:19 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Oct 12 19:53:19 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:19 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:19 volumio-schlafzimmer go-librespot[1633]: go-librespot daemon starting...
Oct 12 19:53:19 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:19+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:19 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:19+02:00" level=debug msg="app state loaded"
Oct 12 19:53:19 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:19+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:20 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:20 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:20 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:20 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:20+02:00" level=info msg="zeroconf server listening on port 36531"
Oct 12 19:53:20 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:20+02:00" level=debug msg="obtained new client token: AAHIFm0/Vmt3m0SOhLmOJGuwvu9Fi8KfXbFlCsEMXZgPsCBlUPOSA42Ud1rpxSnP75Tw/+shZXe66DalPBPjkPWT/VNwHRkkTfJLOoa+1ZCgMri7SSFjdttz9mzfBZJQdi2m4KEqyWRFsOUvVNPHMUBuNT59oPX2F95Yv0HJwbyGLJmagWncbrW6Yo1C67j2+glIIP0ujvll8gEdzN47tYiYHQ4AxMYXTDwYRYXRNNdLbSJIu632jTfstw=="
Oct 12 19:53:20 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:20+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:20 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:20+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:20 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:20+02:00" level=debug msg="completed challenge"
Oct 12 19:53:20 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:20+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11
Oct 12 19:53:20 volumio-schlafzimmer go-librespot[1633]: time="2026-10-12T19:53:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:20 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:20 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: Completed starting MyVolumio Plugin
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]:  [Metrics]  CommandRouter:  74s 752.87ms 
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumiosetStartupVolume
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::Close All Modals sent
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::Close All Modals sent
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:20 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: Received Get System Info
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: Discovery: Getting this device information
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: Listing playlists
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: Received Get System Info
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: Discovery: Getting this device information
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: Received Get System Info
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: Discovery: Getting this device information
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: Listing playlists
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Oct 12 19:53:21 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Oct 12 19:53:22 volumio-schlafzimmer volumio[668]: info: FusionDsp -
Oct 12 19:53:22 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:53:22 volumio-schlafzimmer volumio[668]: info: FusionDsp - undefined
Oct 12 19:53:22 volumio-schlafzimmer volumio[668]: info: FusionDsp -
Oct 12 19:53:23 volumio-schlafzimmer volumio[668]: info: FusionDsp -
Oct 12 19:53:23 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm ThreadPoolForeg: bg 558: bad block bitmap checksum
Oct 12 19:53:23 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3) in ext4_mb_clear_bb:6642: Filesystem failed CRC
Oct 12 19:53:23 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm ThreadPoolForeg: bg 490: bad block bitmap checksum
Oct 12 19:53:23 volumio-schlafzimmer volumio[668]: info: FusionDsp -
Oct 12 19:53:23 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:23 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Oct 12 19:53:23 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:23 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:23 volumio-schlafzimmer go-librespot[1655]: go-librespot daemon starting...
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: FusionDsp - undefined
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=debug msg="app state loaded"
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: FusionDsp - undefined
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: FusionDsp - undefined
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: Received Get System Info
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: Discovery: Getting this device information
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Oct 12 19:53:24 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=info msg="zeroconf server listening on port 40385"
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=debug msg="obtained new client token: AAEYVAI1kewFSn8Ee8zDha2vJ04GAPtjQNjDkTu1P/0jJYtBYM4pZGjybnEVX+AEfKFu95mKfcO44G4+x8xWIa9AzbKokbUX49hhY2SsZSkHJS9QApEoHJ3vZFcxcLNu5EfUiZGMbh989NG2qAqM1p29JMr+IkfJu1RFiOwGp9t03m/uqkCwPZU23+bkmnrEIuevwWC3jeAa7nCCWA81hGJ0sEo2La5sEqlf6TWMjnzAB+B3axou8S7piQ=="
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:24 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:24+02:00" level=debug msg="completed challenge"
Oct 12 19:53:25 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:25+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:25 volumio-schlafzimmer go-librespot[1655]: time="2026-10-12T19:53:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:25 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:25 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:27 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_inode_bitmap:105: comm ThreadPoolForeg: Corrupt inode bitmap - block_group = 528, inode_bitmap = 4325393
Oct 12 19:53:27 volumio-schlafzimmer kernel: EXT4-fs warning (device mmcblk0p3): ext4_dirblock_csum_verify:406: inode #1052201: comm ThreadPoolForeg: No space for directory leaf checksum. Please run e2fsck -D.
Oct 12 19:53:27 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): htree_dirblock_to_tree:1083: inode #1052201: comm ThreadPoolForeg: Directory block failed checksum
Oct 12 19:53:27 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:53:27 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:27 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm ThreadPoolForeg: bg 528: bad block bitmap checksum
Oct 12 19:53:27 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm ThreadPoolForeg: bg 529: bad block bitmap checksum
Oct 12 19:53:27 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3) in ext4_free_inode:362: Corrupt filesystem
Oct 12 19:53:27 volumio-schlafzimmer volumio[668]: info: BOOT COMPLETED
Oct 12 19:53:28 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:28 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Oct 12 19:53:28 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:28 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:28 volumio-schlafzimmer go-librespot[1695]: go-librespot daemon starting...
Oct 12 19:53:28 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:28+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:28 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:28+02:00" level=debug msg="app state loaded"
Oct 12 19:53:28 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:28+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:29 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:29+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:29 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:29+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:29 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:29+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:29 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:29+02:00" level=info msg="zeroconf server listening on port 36665"
Oct 12 19:53:29 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:29+02:00" level=debug msg="obtained new client token: AAHLtevJ4YzyIrq0+54DelmtsUn1foBnOCseSTMRKJ6x16Y/vPncNv8WAjJx0VBjAvGswDhTHlmEBMBqwiayho/6mcgJ/8gLUxzyHevgoVGQv8V3hNs8a2FntWK2dJL3OKE8Xa36uVKOkvyvn1bnvtDAaPAGMYk8SEPoLVox6dHFyZj4tkpOPkOsx8kxjDdrkWaGK/1QnU0s8V2Cc8EAPkEJWoZjThXksNK9KaaRj5aLWrqAx/En3Qn3vg=="
Oct 12 19:53:29 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:29 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:29+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:29 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:29+02:00" level=debug msg="completed challenge"
Oct 12 19:53:29 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:29+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:29 volumio-schlafzimmer go-librespot[1695]: time="2026-10-12T19:53:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:29 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:29 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:29 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 12 19:53:29 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Oct 12 19:53:30 volumio-schlafzimmer volumio[668]: info: Initializing connection to go-librespot Websocket
Oct 12 19:53:30 volumio-schlafzimmer volumio[668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Oct 12 19:53:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Oct 12 19:53:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs
Oct 12 19:53:30 volumio-schlafzimmer volumio[668]: info: Received Get System Version
Oct 12 19:53:30 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: Received Get System Version
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: Received Get System Info
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: Discovery: Getting this device information
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::volumioGetState
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:31 volumio-schlafzimmer volumio[668]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 12 19:53:32 volumio-schlafzimmer volumio-remote-updater[468]: No test mode
Oct 12 19:53:32 volumio-schlafzimmer volumio-remote-updater[468]: No alpha test mode
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for BLE notification issues
\nFix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\nUpdate Tidal SDK
\nUpdate Qobuz Connect SDK
\n\n","title":"Update v3.912","updateavailable":true}
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]: SyntaxError: /data/configuration/plugins.json: Unexpected string in JSON at position 17
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]:     at JSON.parse ()
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]:     at Object.readFileSync (/volumio/node_modules/jsonfile/index.js:69:17)
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]:     at PluginManager.getAllPlugNames (/volumio/app/pluginmanager.js:568:21)
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]:     at PluginManager.getPluginsMatrix (/volumio/app/pluginmanager.js:592:26)
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]:     at PluginManager.listPluginsBrokenByNewVersion (/volumio/app/pluginmanager.js:1224:22)
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]:     at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:978:60)
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]:     at Socket.emit (events.js:315:20)
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]:     at /volumio/node_modules/socket.io/lib/socket.js:528:12
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]:     at processTicksAndRejections (internal/process/task_queues.js:75:11)
Oct 12 19:53:32 volumio-schlafzimmer volumio[668]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 12 19:53:32 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:32 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Oct 12 19:53:32 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:32 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:32 volumio-schlafzimmer go-librespot[1869]: go-librespot daemon starting...
Oct 12 19:53:32 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:32+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:32 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:32+02:00" level=debug msg="app state loaded"
Oct 12 19:53:32 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:32+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:33 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:33+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Oct 12 19:53:33 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:33+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]"
Oct 12 19:53:33 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:33+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]"
Oct 12 19:53:33 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:33+02:00" level=info msg="zeroconf server listening on port 44077"
Oct 12 19:53:33 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:33+02:00" level=debug msg="obtained new client token: AAHf8tKZSYO1NPMtPGBOn3T5Bo/2pS1WXqL6+ZLmSWhe3QBMhjMDjAPFKfEwCw7zcrR4bAeBE7dbvYtuQ4rBcINTYnMfEue/n/gxDj7q9YW9sGk/sva7bkyDE/rv2M98PGO7WY0jxEzA3dggpNg85SYsTMPTGO8iRQ/aKy7NSUWQFwfdIkVTpZXE94vHIZKHnCwwm0WyrCO7M2ty3GQLbLr9JRHIRfXroW2TXawUykUt7/vJtQWQJFFx6g=="
Oct 12 19:53:33 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:33 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:33+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:33 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:33+02:00" level=debug msg="completed challenge"
Oct 12 19:53:33 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:33+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:33 volumio-schlafzimmer go-librespot[1869]: time="2026-10-12T19:53:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:33 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:33 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:34 volumio-schlafzimmer sudo[1899]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-12 19:52
Oct 12 19:53:34 volumio-schlafzimmer sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:34 volumio-schlafzimmer sudo[1899]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:35 volumio-schlafzimmer volumio-remote-updater[468]: [2026-10-12 19:53:35] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Oct 12 19:53:35 volumio-schlafzimmer volumio-remote-updater[468]: [2026-10-12 19:53:35] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Oct 12 19:53:35 volumio-schlafzimmer systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:35 volumio-schlafzimmer systemd[1]: volumio.service: Failed with result 'exit-code'.
Oct 12 19:53:35 volumio-schlafzimmer systemd[1]: Started dynamicswap service.
Oct 12 19:53:35 volumio-schlafzimmer systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Oct 12 19:53:35 volumio-schlafzimmer systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1.
Oct 12 19:53:35 volumio-schlafzimmer systemd[1]: Stopped Volumio Backend Module.
Oct 12 19:53:35 volumio-schlafzimmer systemd[1]: Started Volumio Backend Module.
Oct 12 19:53:35 volumio-schlafzimmer systemd[1]: dynamicswap.service: Succeeded.
Oct 12 19:53:36 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:36 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Oct 12 19:53:36 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:36 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:36 volumio-schlafzimmer go-librespot[1919]: go-librespot daemon starting...
Oct 12 19:53:36 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:36+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:36 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:36+02:00" level=debug msg="app state loaded"
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:36+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:37+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:37+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:37+02:00" level=info msg="zeroconf server listening on port 45527"
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:37+02:00" level=debug msg="obtained new client token: AAG8XF2jEHfu2q5Owp4gtB0H8aZlWW5sMmY013D0O/5FRfu8gFg5LcsyMavhHXMsXcEb0mpP4d6Y0wO2hwsgL1aGGz+MpA4yv1YWwyrZU3cJz+ggqTxxk5rBf35fe+iG039LjPw4s+Lt2YE4CUGyHuTAuNmF/QiIqPanyfCgFFBqSJrJggeivA7jf0dBx1Nzwg0+CuzALjkfaC3IMnktu+Pe2JOH4qbKHFX3uA6vq5SqepOJVv3Ksi0tSA=="
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:37+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:37+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:37+02:00" level=debug msg="completed challenge"
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:37+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:37 volumio-schlafzimmer go-librespot[1919]: time="2026-10-12T19:53:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:37 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:37 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:39 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm kworker/u12:3: bg 483: bad block bitmap checksum
Oct 12 19:53:40 volumio-schlafzimmer volumio-remote-updater[468]: [2026-10-12 19:53:40] [connect] Successful connection
Oct 12 19:53:40 volumio-schlafzimmer volumio[1912]: info: -------------------------------------------
Oct 12 19:53:40 volumio-schlafzimmer volumio[1912]: info: -----            Volumio3              ----
Oct 12 19:53:40 volumio-schlafzimmer volumio[1912]: info: -------------------------------------------
Oct 12 19:53:40 volumio-schlafzimmer volumio[1912]: info: -----          System startup          ----
Oct 12 19:53:40 volumio-schlafzimmer volumio[1912]: info: -------------------------------------------
Oct 12 19:53:41 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:41 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Oct 12 19:53:41 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:41 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:41 volumio-schlafzimmer go-librespot[1936]: go-librespot daemon starting...
Oct 12 19:53:41 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:41+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:41 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:41+02:00" level=debug msg="app state loaded"
Oct 12 19:53:41 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:41+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:41 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:41+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:41 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:41+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:41 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:41+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:41 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:41+02:00" level=info msg="zeroconf server listening on port 39605"
Oct 12 19:53:41 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:41+02:00" level=debug msg="obtained new client token: AAHXOe0J5r4KUzej6VUyHOLLs20nQehQn19c/DMtHf7+BRhXi5ApWSd81G3ilsJqXU+KqRN/esWHXI4ptARz40V12shi7ZMcynF+DGC7f3E7yXoG6471uRN3k7zImd1aP9I/nNYiMMyOziT6P7ssUIT+NP2ZXmKFcvQztdhbJ9OrmEDjgim1dl2BW2ZOpya6f8Gx/yGRTpkaAr8H6nbDzDTu6Rm6g6D3sVbHvZBhGmYvKi1DSyiV4yd+7g=="
Oct 12 19:53:41 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:42 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:42+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:42 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:42+02:00" level=debug msg="completed challenge"
Oct 12 19:53:42 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:42+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:42 volumio-schlafzimmer go-librespot[1936]: time="2026-10-12T19:53:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:42 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:42 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: MYVOLUMIO Environment detected
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin music_service/airplay_emulation. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin music_service/inputs. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin music_service/mpd. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin music_service/webradio. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin music_service/example_plugin. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin music_service/last_100. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin music_service/upnp_browser. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin audio_interface/outputs. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin audio_interface/upnp. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin audio_interface/alsa_controller. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin miscellanea/alarm-clock. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin miscellanea/albumart. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin miscellanea/appearance. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin miscellanea/wizard. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin miscellanea/my_music. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin user_interface/websocket. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin user_interface/mpdemulation. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin user_interface/rest_api. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin system_controller/updater_comm. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin system_controller/network. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin system_controller/networkfs. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin system_controller/services. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin system_controller/system. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin system_controller/i2s_dacs. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin system_controller/volumiodiscovery. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Found new core plugin system_controller/volumio_command_line_client. Adding it
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Plugin folders cleanup
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning into folder /volumio/app/plugins/
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning category audio_interface
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning category miscellanea
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning category music_service
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning category plugins.json
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning category system_controller
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning category user_interface
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning into folder /data/plugins/
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning category audio_interface
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning category music_service
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning category system_controller
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Scanning category user_interface
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Plugin folders cleanup completed
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: -------------------------------------------
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: -----      Core plugins startup        ----
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: -------------------------------------------
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Loading plugins from folder /volumio/app/plugins/
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Adding plugin upnp to MyMusic Plugins
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Adding plugin airplay_emulation to MyMusic Plugins
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Adding plugin upnp_browser to MyMusic Plugins
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Loading plugins from folder /data/plugins/
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: Loading plugin "system"...
Oct 12 19:53:42 volumio-schlafzimmer volumio[1912]: info: No id defined. Creating one
Oct 12 19:53:43 volumio-schlafzimmer volumio[1912]: info: Loading plugin "appearance"...
Oct 12 19:53:45 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm kworker/u12:3: bg 522: bad block bitmap checksum
Oct 12 19:53:45 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:45 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Oct 12 19:53:45 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:45 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:45 volumio-schlafzimmer go-librespot[1950]: go-librespot daemon starting...
Oct 12 19:53:45 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:45+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:45 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:45+02:00" level=debug msg="app state loaded"
Oct 12 19:53:45 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:45+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:45 volumio-schlafzimmer volumio[1912]: info: Loading plugin "network"...
Oct 12 19:53:45 volumio-schlafzimmer volumio[1912]: info: Refreshing Cached IP Addresses
Oct 12 19:53:45 volumio-schlafzimmer sudo[1959]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 12 19:53:45 volumio-schlafzimmer sudo[1961]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 12 19:53:45 volumio-schlafzimmer sudo[1959]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:45 volumio-schlafzimmer sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:45 volumio-schlafzimmer volumio[1912]: info: Loading plugin "services"...
Oct 12 19:53:45 volumio-schlafzimmer sudo[1959]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:45 volumio-schlafzimmer volumio[1912]: info: Loading plugin "alsa_controller"...
Oct 12 19:53:46 volumio-schlafzimmer sudo[1961]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:46 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:46+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:46 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:46+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:46 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:46+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:46 volumio-schlafzimmer sudo[1971]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Oct 12 19:53:46 volumio-schlafzimmer sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:46 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:46+02:00" level=info msg="zeroconf server listening on port 45247"
Oct 12 19:53:46 volumio-schlafzimmer sudo[1971]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:46 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:46+02:00" level=debug msg="obtained new client token: AAH1HGtJ3G1tpa2umXJqocA+JxVTCkVuw9WS0dZBP548Lwz/cvYKFYzagoSbu0LozlsPcJ4iZZRkZZtBgWKX+PFgZVr0go4CSRFYhAbiRvh1k5r8M4BUVrMXECCt/TJiNSmtNAwlL39eRHylV1sOH4HmwjSF9EhFliHMNWaYS9VM9o4r4CLd4dTN8HmSjzguOsJ8EIwOZVtoNXJ+/JEL6Lb68BCUS5q1i2mColTY4dujQyvfoJ053z0="
Oct 12 19:53:46 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:46+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: warn: The ALSA output card is not set, defaulting to card [object Object]
Oct 12 19:53:46 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:46+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:46 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:46+02:00" level=debug msg="completed challenge"
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out
Oct 12 19:53:46 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:46+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Oct 12 19:53:46 volumio-schlafzimmer go-librespot[1950]: time="2026-10-12T19:53:46+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:46 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:46 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Loading plugin "wizard"...
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Loading plugin "networkfs"...
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Starting Udev Watcher for removable devices
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Ignoring mount for partition: boot
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Ignoring mount for partition: volumio
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Ignoring mount for partition: volumio_data
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Loading plugin "volumio_command_line_client"...
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Loading plugin "upnp"...
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: [1791827626966] Starting Upmpd Daemon
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Loading plugin "my_music"...
Oct 12 19:53:46 volumio-schlafzimmer volumio[1912]: info: Loading plugin "mpd"...
Oct 12 19:53:47 volumio-schlafzimmer volumio[1912]: info: Loading plugin "upnp_browser"...
Oct 12 19:53:48 volumio-schlafzimmer volumio[1912]: info: Starting UPNP Browser
Oct 12 19:53:48 volumio-schlafzimmer volumio[1912]: info: Loading plugin "alarm-clock"...
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Loading plugin "airplay_emulation"...
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Starting Shairport Sync
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Loading plugin "last_100"...
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Loading plugin "webradio"...
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Loading plugin "i2s_dacs"...
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Loading plugin "volumiodiscovery"...
Oct 12 19:53:49 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:49 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Oct 12 19:53:49 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 12 19:53:49 volumio-schlafzimmer node[1912]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: *** WARNING *** For more information see 
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: *** WARNING *** For more information see 
Oct 12 19:53:49 volumio-schlafzimmer node[1912]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 12 19:53:49 volumio-schlafzimmer node[1912]: *** WARNING *** For more information see 
Oct 12 19:53:49 volumio-schlafzimmer node[1912]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 12 19:53:49 volumio-schlafzimmer node[1912]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 12 19:53:49 volumio-schlafzimmer node[1912]: *** WARNING *** For more information see 
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Applying required configuration parameters for plugin volumiodiscovery
Oct 12 19:53:49 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Discovery: Started advertising with name: undefined
Oct 12 19:53:49 volumio-schlafzimmer go-librespot[2001]: go-librespot daemon starting...
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Plugin spop is not enabled
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Plugin ytmusic is not enabled
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Plugin autostart is not enabled
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Loading plugin "outputs"...
Oct 12 19:53:49 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:49+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:49 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:49+02:00" level=debug msg="app state loaded"
Oct 12 19:53:49 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:49+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Loading plugin "albumart"...
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Plugin example_plugin is not enabled
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Loading plugin "inputs"...
Oct 12 19:53:49 volumio-schlafzimmer volumio[1912]: info: Loading plugin "updater_comm"...
Oct 12 19:53:50 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm kworker/u12:3: bg 498: bad block bitmap checksum
Oct 12 19:53:50 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:50+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:50 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:50+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:50 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:50+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:50 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:50+02:00" level=info msg="zeroconf server listening on port 46859"
Oct 12 19:53:50 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:50+02:00" level=debug msg="obtained new client token: AAExN7oCNoio15BaKJt+opEAu7P0a/awLF5gAaD4xBymAiWNbTobkb04wygRmmsQNfqDFzgYx8L2rPlClNDwWgxPNW63jLrLno+Abd4849L99ivjUSfwbdbkec7Kddg0A1h/5jwJFgh2kGy6+taewirenqHZLJdK59cmJIdSKcVStWQpcOt7Zpe+NiJyHkogYt10q8PpWiY3lxqopHxjHD0RMz8v1RDlcGoGAlpQWE9ufUotY/mYV6tgTw=="
Oct 12 19:53:50 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:50+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:50 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:50+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:50 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:50+02:00" level=debug msg="completed challenge"
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: info: Plugin mpdemulation is not enabled
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: info: Loading plugin "rest_api"...
Oct 12 19:53:50 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:50+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: info: Loading plugin "websocket"...
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: info: Starting Socket.io Server version 2.3.0
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: info: Plugin fusiondsp is not enabled
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: info: Plugin podcast is not enabled
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: info: Plugin touch_display is not enabled
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: info: Loading i18n strings for locale de
Oct 12 19:53:50 volumio-schlafzimmer go-librespot[2001]: time="2026-10-12T19:53:50+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:50 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:50 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: Updating browse sources language
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:53:50 volumio-schlafzimmer volumio[1912]: Forking 3 albumart workers
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::initPlayerControls
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: Express server listening on port 3000
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]:  [Metrics]  WebUI:  13s 15.80ms 
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::resetVolumioState
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::getcurrentVolume
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioRetrievevolume
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::getcurrentVolume
Oct 12 19:53:51 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioRetrievevolume
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: info: Volumio Network Manager: Network status updated: 1
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: wlan0     Interface doesn't support scanning.
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: info: Cannot use regular scanning, forcing with ap-force
Oct 12 19:53:52 volumio-schlafzimmer sudo[2046]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Oct 12 19:53:52 volumio-schlafzimmer sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:52 volumio-schlafzimmer sudo[2046]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: command failed: No such device (-19)
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: command failed: No such device (-19)
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.33 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 1
Oct 12 19:53:52 volumio-schlafzimmer volumio-remote-updater[468]: [2026-10-12 19:53:52] [connect] Successful connection
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.33 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 3
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: verbose: New Socket.io Connection to 192.168.178.41 from 192.168.178.33 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 5
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: info: VolumeController:: Volume=96 Mute =false
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::pushState
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioPushState
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::updateTrackBlock
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: info: CorePlayQueue::getTrackBlock
Oct 12 19:53:52 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioRetrievevolume
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: Reloading queue from file
Oct 12 19:53:53 volumio-schlafzimmer volumio-remote-updater[468]: [2026-10-12 19:53:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1791827632 101
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::setRepeat null single undefined
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::pushState
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioPushState
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::setRandom null
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::pushState
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioPushState
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: Setting Device type: Raspberry PI
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: Completed loading Core Plugins
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: Preparing to generate the ALSA configuration file
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: VolumeController:: Volume=96 Mute =false
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::pushState
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioPushState
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::updateTrackBlock
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CorePlayQueue::getTrackBlock
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioRetrievevolume
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: VolumeController:: Volume=96 Mute =false
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreStateMachine::pushState
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CorePlayQueue::getTrack 0
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioPushState
Oct 12 19:53:53 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_inode_bitmap:105: comm node: Corrupt inode bitmap - block_group = 481, inode_bitmap = 3932178
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: info: Asound.conf file written
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: Starting albumart workers
Oct 12 19:53:53 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:53 volumio-schlafzimmer volumio[1912]: Starting albumart workers
Oct 12 19:53:53 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Oct 12 19:53:53 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:53 volumio-schlafzimmer sudo[2060]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Oct 12 19:53:53 volumio-schlafzimmer sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:53 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:53 volumio-schlafzimmer go-librespot[2061]: go-librespot daemon starting...
Oct 12 19:53:54 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm mv: bg 545: bad block bitmap checksum
Oct 12 19:53:54 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3) in ext4_mb_clear_bb:6642: Filesystem failed CRC
Oct 12 19:53:54 volumio-schlafzimmer sudo[2060]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:54 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:54+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:54 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:54+02:00" level=debug msg="app state loaded"
Oct 12 19:53:54 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:54+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: Starting albumart workers
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: Output device has changed, restarting MPD
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: Output device has changed, restarting Shairport Sync
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:54 volumio-schlafzimmer sudo[2073]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 12 19:53:54 volumio-schlafzimmer sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:54 volumio-schlafzimmer sudo[2073]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:54 volumio-schlafzimmer sudo[2075]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 12 19:53:54 volumio-schlafzimmer sudo[2075]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: ___________ START PLUGINS ___________
Oct 12 19:53:54 volumio-schlafzimmer systemd[1]: Stopping Music Player Daemon...
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: ControllerMpd::onStart: Initializing MPD
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: Creating MPD Configuration file
Oct 12 19:53:54 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:54+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Oct 12 19:53:54 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:54+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Oct 12 19:53:54 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:54+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 12 19:53:54 volumio-schlafzimmer sudo[2082]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 12 19:53:54 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:54+02:00" level=info msg="zeroconf server listening on port 41609"
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: [1791827634858] CoreMusicLibrary::Adding element Medienserver
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:53:54 volumio-schlafzimmer sudo[2082]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:54 volumio-schlafzimmer sudo[2082]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:54 volumio-schlafzimmer sudo[2084]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 12 19:53:54 volumio-schlafzimmer sudo[2084]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:54 volumio-schlafzimmer volumio[1912]: info: UPNP Browser: Client initialized successfully
Oct 12 19:53:55 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:55+02:00" level=debug msg="obtained new client token: AAGoya8FTEDp07MbCyOfzgnfSrD+27xAR6G43s117HJIdjqpklJGN0rMYfEPGm1LfkdAdXvHzHIcEhF9oaUjMeRSsl+y0HItB4qIDCvRH2BdM/GE+rWfHqYPN32J69z+7xujhS/GKWYG1DM91Tztyw/d0MqupUs4POi1RS7RhesAmBS5n7CmVMX9LOsaCiVUuSIEKcu7wzMY1mt8nRUpE7FIWMDKddnfLFb4mmqRne9k7ngLxJ7mJhIXdQ=="
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:55 volumio-schlafzimmer kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm kworker/u12:2: bg 1342: bad block bitmap checksum
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:55 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Oct 12 19:53:55 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:55+02:00" level=debug msg="completed keyexchange"
Oct 12 19:53:55 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:55+02:00" level=debug msg="completed challenge"
Oct 12 19:53:55 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:55+02:00" level=info msg="authenticated AP" username="de***********er"
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:55 volumio-schlafzimmer go-librespot[2061]: time="2026-10-12T19:53:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Oct 12 19:53:55 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Oct 12 19:53:55 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Oct 12 19:53:55 volumio-schlafzimmer systemd[1]: mpd.service: Succeeded.
Oct 12 19:53:55 volumio-schlafzimmer systemd[1]: Stopped Music Player Daemon.
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: [1791827635653] CoreMusicLibrary::Adding element Last_100
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: [1791827635662] CoreMusicLibrary::Adding element Webradio
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 12 19:53:55 volumio-schlafzimmer systemd[1]: Starting Music Player Daemon...
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: Initializing BBC Radios
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 12 19:53:55 volumio-schlafzimmer volumio[1912]: info: Volumio Calling Home
Oct 12 19:53:56 volumio-schlafzimmer sudo[2094]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 12 19:53:56 volumio-schlafzimmer sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 12 19:53:56 volumio-schlafzimmer sudo[2094]: pam_unix(sudo:session): session closed for user root
Oct 12 19:53:57 volumio-schlafzimmer volumio[1912]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port)
Oct 12 19:53:57 volumio-schlafzimmer volumio[1912]: info: Discovery: Started advertising with name: undefined
Oct 12 19:53:57 volumio-schlafzimmer volumio[1912]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 12 19:53:57 volumio-schlafzimmer volumio[1912]: TypeError: Cannot read property 'charAt' of undefined
Oct 12 19:53:57 volumio-schlafzimmer volumio[1912]:     at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24)
Oct 12 19:53:57 volumio-schlafzimmer volumio[1912]:     at listOnTimeout (internal/timers.js:554:17)
Oct 12 19:53:57 volumio-schlafzimmer volumio[1912]:     at processTimers (internal/timers.js:497:7)
Oct 12 19:53:57 volumio-schlafzimmer volumio[1912]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 12 19:53:58 volumio-schlafzimmer volumiologrotate[476]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory
Oct 12 19:53:58 volumio-schlafzimmer volumiologrotate[476]: ls: cannot access 'SCHLAFZIMMER': No such file or directory
Oct 12 19:53:58 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Oct 12 19:53:58 volumio-schlafzimmer systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Oct 12 19:53:58 volumio-schlafzimmer systemd[1]: Stopped go-librespot Daemon.
Oct 12 19:53:58 volumio-schlafzimmer systemd[1]: Started go-librespot Daemon.
Oct 12 19:53:58 volumio-schlafzimmer go-librespot[2217]: go-librespot daemon starting...
Oct 12 19:53:58 volumio-schlafzimmer go-librespot[2217]: time="2026-10-12T19:53:58+02:00" level=info msg="running go-librespot 0.4.0"
Oct 12 19:53:58 volumio-schlafzimmer go-librespot[2217]: time="2026-10-12T19:53:58+02:00" level=debug msg="app state loaded"
Oct 12 19:53:58 volumio-schlafzimmer go-librespot[2217]: time="2026-10-12T19:53:58+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 12 19:53:58 volumio-schlafzimmer sudo[2226]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-12 19:52
Oct 12 19:53:58 volumio-schlafzimmer sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET"
VOLUMIO_VERSION="3.886"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"