-- Logs begin at Thu 2020-05-28 19:51:46 UTC, end at Thu 2025-07-10 17:18:58 UTC. -- Jul 10 17:17:36 volumio systemd[1]: Time has been changed Jul 10 17:17:37 volumio wireless.js[693]: trying... Jul 10 17:17:37 volumio sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:37 volumio sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:37 volumio sudo[880]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:38 volumio wireless.js[693]: trying... Jul 10 17:17:38 volumio sudo[889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:38 volumio sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:38 volumio sudo[889]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:39 volumio wireless.js[693]: trying... Jul 10 17:17:39 volumio sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:39 volumio sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:39 volumio sudo[898]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:39 volumio kernel: Voltage normalised (0x00000000) Jul 10 17:17:39 volumio volumio-remote-updater[544]: [2025-07-10 17:17:39] [info] asio async_connect error: system:111 (Connection refused) Jul 10 17:17:39 volumio volumio-remote-updater[544]: [2025-07-10 17:17:39] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 17:17:39 volumio volumio-remote-updater[544]: [2025-07-10 17:17:39] [error] handle_connect error: Underlying Transport Error Jul 10 17:17:40 volumio wireless.js[693]: trying... Jul 10 17:17:40 volumio sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:40 volumio sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:40 volumio sudo[907]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:41 volumio wireless.js[693]: trying... Jul 10 17:17:41 volumio sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:41 volumio sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:41 volumio sudo[916]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:42 volumio wireless.js[693]: trying... Jul 10 17:17:42 volumio sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:42 volumio sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:42 volumio sudo[925]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:43 volumio wireless.js[693]: trying... Jul 10 17:17:43 volumio sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:43 volumio sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:43 volumio sudo[934]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:43 volumio kernel: Under-voltage detected! (0x00050005) Jul 10 17:17:43 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:17:44 volumio wireless.js[693]: trying... Jul 10 17:17:44 volumio sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:44 volumio sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:44 volumio sudo[943]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:45 volumio wireless.js[693]: trying... Jul 10 17:17:45 volumio sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:45 volumio sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:45 volumio sudo[952]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:46 volumio wireless.js[693]: trying... Jul 10 17:17:46 volumio sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:46 volumio sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:46 volumio sudo[961]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:47 volumio wireless.js[693]: trying... Jul 10 17:17:47 volumio sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:47 volumio sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:47 volumio sudo[970]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:48 volumio wireless.js[693]: trying... Jul 10 17:17:48 volumio sudo[979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:48 volumio sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:48 volumio sudo[979]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:49 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:17:49 volumio wireless.js[693]: trying... Jul 10 17:17:49 volumio sudo[988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:49 volumio sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:49 volumio sudo[988]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:49 volumio kernel: Voltage normalised (0x00000000) Jul 10 17:17:49 volumio volumio-remote-updater[544]: [2025-07-10 17:17:49] [info] asio async_connect error: system:111 (Connection refused) Jul 10 17:17:49 volumio volumio-remote-updater[544]: [2025-07-10 17:17:49] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 17:17:49 volumio volumio-remote-updater[544]: [2025-07-10 17:17:49] [error] handle_connect error: Underlying Transport Error Jul 10 17:17:50 volumio wireless.js[693]: trying... Jul 10 17:17:50 volumio sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:50 volumio sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:50 volumio sudo[997]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:51 volumio wireless.js[693]: trying... Jul 10 17:17:51 volumio sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:51 volumio sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:51 volumio sudo[1006]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:52 volumio wireless.js[693]: trying... Jul 10 17:17:52 volumio sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 17:17:52 volumio sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:17:52 volumio sudo[1015]: pam_unix(sudo:session): session closed for user root Jul 10 17:17:53 volumio wireless.js[693]: Overtime, starting plan B Jul 10 17:17:53 volumio systemd[1]: Started Wireless Services. Jul 10 17:17:53 volumio systemd[1]: Starting Volumio Backend Module... Jul 10 17:17:53 volumio systemd[1]: Started Volumio Backend Module. Jul 10 17:17:53 volumio systemd[1]: Starting Multi-User System. Jul 10 17:17:53 volumio systemd[1]: Reached target Multi-User System. Jul 10 17:17:53 volumio systemd[1]: Starting Graphical Interface. Jul 10 17:17:53 volumio systemd[1]: Reached target Graphical Interface. Jul 10 17:17:53 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 10 17:17:53 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 10 17:17:53 volumio systemd[1]: Startup finished in 8.588s (kernel) + 34.785s (userspace) = 43.373s. Jul 10 17:17:54 volumio kernel: Under-voltage detected! (0x00050005) Jul 10 17:17:54 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:17:57 volumio volumio[1024]: info: ------------------------------------------- Jul 10 17:17:57 volumio volumio[1024]: info: ----- Volumio2 ---- Jul 10 17:17:57 volumio volumio[1024]: info: ------------------------------------------- Jul 10 17:17:57 volumio volumio[1024]: info: ----- System startup ---- Jul 10 17:17:57 volumio volumio[1024]: info: ------------------------------------------- Jul 10 17:17:57 volumio volumio[1024]: info: Plugin folders cleanup Jul 10 17:17:57 volumio volumio[1024]: info: Scanning into folder /volumio/app/plugins/ Jul 10 17:17:57 volumio volumio[1024]: info: Scanning category audio_interface Jul 10 17:17:57 volumio volumio[1024]: info: Scanning category miscellanea Jul 10 17:17:57 volumio volumio[1024]: info: Scanning category music_service Jul 10 17:17:57 volumio volumio[1024]: info: Scanning category plugins.json Jul 10 17:17:57 volumio volumio[1024]: info: Scanning category system_controller Jul 10 17:17:57 volumio volumio[1024]: info: Scanning category user_interface Jul 10 17:17:57 volumio volumio[1024]: info: Scanning into folder /data/plugins/ Jul 10 17:17:57 volumio volumio[1024]: info: Scanning category music_service Jul 10 17:17:57 volumio volumio[1024]: info: Plugin folders cleanup completed Jul 10 17:17:57 volumio volumio[1024]: info: ------------------------------------------- Jul 10 17:17:57 volumio volumio[1024]: info: ----- Core plugins startup ---- Jul 10 17:17:57 volumio volumio[1024]: info: ------------------------------------------- Jul 10 17:17:57 volumio volumio[1024]: info: Loading plugins from folder /volumio/app/plugins/ Jul 10 17:17:57 volumio volumio[1024]: info: Loading plugins from folder /data/plugins/ Jul 10 17:17:57 volumio volumio[1024]: info: Loading plugin "system"... Jul 10 17:17:57 volumio volumio[1024]: info: Loading plugin "appearance"... Jul 10 17:17:59 volumio volumio[1024]: info: Loading plugin "network"... Jul 10 17:17:59 volumio volumio[1024]: info: Loading plugin "services"... Jul 10 17:17:59 volumio volumio[1024]: info: Loading plugin "alsa_controller"... Jul 10 17:17:59 volumio volumio[1024]: info: Loading plugin "wizard"... Jul 10 17:17:59 volumio volumio[1024]: info: Loading plugin "volumio_command_line_client"... Jul 10 17:17:59 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:17:59 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:17:59 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:17:59 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:17:59 volumio volumio[1024]: info: Loading plugin "upnp"... Jul 10 17:17:59 volumio volumio[1024]: info: [1752167879714] Starting Upmpd Daemon Jul 10 17:17:59 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 17:17:59 volumio volumio[1024]: info: Loading plugin "my_music"... Jul 10 17:17:59 volumio volumio[1024]: info: Loading plugin "mpd"... Jul 10 17:17:59 volumio volumio-remote-updater[544]: [2025-07-10 17:17:59] [connect] Successful connection Jul 10 17:18:00 volumio volumio[1024]: info: Plugin upnp_browser is not enabled Jul 10 17:18:00 volumio volumio[1024]: info: Loading plugin "networkfs"... Jul 10 17:18:00 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 17:18:00 volumio volumio[1024]: info: Loading plugin "alarm-clock"... Jul 10 17:18:00 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:00 volumio volumio[1024]: info: Loading plugin "airplay_emulation"... Jul 10 17:18:00 volumio volumio[1024]: info: [1752167880728] Starting Shairport Sync Jul 10 17:18:00 volumio volumio[1024]: info: Loading plugin "last_100"... Jul 10 17:18:00 volumio volumio[1024]: info: Loading plugin "webradio"... Jul 10 17:18:01 volumio volumio[1024]: info: Loading plugin "i2s_dacs"... Jul 10 17:18:01 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 17:18:01 volumio volumio[1024]: info: Executing start script for DAC IQaudIO DAC Plus Jul 10 17:18:01 volumio volumio[1024]: info: Loading plugin "volumiodiscovery"... Jul 10 17:18:02 volumio volumio[1024]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 17:18:02 volumio node[1024]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 17:18:02 volumio node[1024]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 17:18:02 volumio node[1024]: *** WARNING *** For more information see Jul 10 17:18:02 volumio node[1024]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 17:18:02 volumio node[1024]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 17:18:02 volumio node[1024]: *** WARNING *** For more information see Jul 10 17:18:02 volumio volumio[1024]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 17:18:02 volumio volumio[1024]: *** WARNING *** For more information see Jul 10 17:18:02 volumio volumio[1024]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 17:18:02 volumio volumio[1024]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 17:18:02 volumio volumio[1024]: *** WARNING *** For more information see Jul 10 17:18:02 volumio volumio[1024]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 10 17:18:02 volumio volumio[1024]: Discovery: StartAdv! undefined Jul 10 17:18:02 volumio volumio[1024]: Discovery: Started advertising... Volumio - undefined Jul 10 17:18:02 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 17:18:02 volumio volumio[1024]: info: Loading plugin "albumart"... Jul 10 17:18:02 volumio volumio[1024]: info: Plugin example_plugin is not enabled Jul 10 17:18:02 volumio volumio[1024]: info: Loading plugin "updater_comm"... Jul 10 17:18:02 volumio volumio[1024]: info: Plugin mpdemulation is not enabled Jul 10 17:18:02 volumio volumio[1024]: info: Loading plugin "rest_api"... Jul 10 17:18:02 volumio volumio[1024]: info: Loading plugin "websocket"... Jul 10 17:18:02 volumio volumio[1024]: info: Loading plugin "spop"... Jul 10 17:18:02 volumio volumio[1024]: Forking 3 albumart workers Jul 10 17:18:04 volumio volumio[1024]: Starting albumart workers Jul 10 17:18:04 volumio volumio[1024]: Starting albumart workers Jul 10 17:18:04 volumio volumio[1024]: Starting albumart workers Jul 10 17:18:04 volumio volumio[1024]: info: Loading plugin "volspotconnect2"... Jul 10 17:18:05 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:06 volumio volumio[1024]: info: ___________ START PLUGINS ___________ Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 17:18:06 volumio volumio[1024]: info: [1752167886114] CoreMusicLibrary::Adding element Last_100 Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 17:18:06 volumio volumio[1024]: info: [1752167886126] CoreMusicLibrary::Adding element Webradio Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 17:18:06 volumio volumio[1024]: [SpotifyConnect] Creating VLS config file Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: [SpotifyConnect] Starting metadata listener Jul 10 17:18:06 volumio sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Jul 10 17:18:06 volumio sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:18:06 volumio volumio[1024]: info: ------------------------------------------- Jul 10 17:18:06 volumio volumio[1024]: info: ----- MyVolumio plugins startup ---- Jul 10 17:18:06 volumio volumio[1024]: info: ------------------------------------------- Jul 10 17:18:06 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jul 10 17:18:06 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Jul 10 17:18:06 volumio volumio[1024]: info: Loading plugins from folder /myvolumio/plugins Jul 10 17:18:06 volumio volumio[1024]: info: Loading plugins from folder /data/myvolumio/plugins Jul 10 17:18:06 volumio systemd[1]: Starting Spotify Daemon... Jul 10 17:18:06 volumio systemd[1]: Started Spotify Daemon. Jul 10 17:18:06 volumio volumio[1024]: info: Loading i18n strings for locale fr Jul 10 17:18:06 volumio sudo[1078]: pam_unix(sudo:session): session closed for user root Jul 10 17:18:06 volumio sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jul 10 17:18:06 volumio volumio[1024]: Updating browse sources language Jul 10 17:18:06 volumio sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:18:06 volumio systemd[1]: Starting Volspotconnect2 Daemon... Jul 10 17:18:06 volumio systemd[1]: Started Volspotconnect2 Daemon. Jul 10 17:18:06 volumio sudo[1084]: pam_unix(sudo:session): session closed for user root Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::initPlayerControls Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: info: BOOT COMPLETED Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::Close All Modals sent Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::Close All Modals sent Jul 10 17:18:06 volumio volumio[1024]: Express server listening on port 3000 Jul 10 17:18:06 volumio volumio[1024]: Volumio Calling Home Jul 10 17:18:06 volumio volumio[1085]: 2025-07-10 17:18:06 [SPTF] 17:18:06.510 I [offline_authorizer.cpp:297] Unable to login offline: no such user Jul 10 17:18:06 volumio volumio[1085]: 2025-07-10 17:18:06 [SPTF] 17:18:06.512 I [ap:1752] Connecting to AP ap.spotify.com:4070 Jul 10 17:18:06 volumio volumio[1093]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Jul 10 17:18:06 volumio volumio[1093]: Reading Config from "volspotify.toml" Jul 10 17:18:06 volumio volumio[1085]: 2025-07-10 17:18:06 [SPTF] 17:18:06.561 I [ap:1226] Connected to AP: 34.158.1.133:4070 Jul 10 17:18:06 volumio volumio[1093]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Jul 10 17:18:06 volumio volumio[1024]: info: CoreStateMachine::resetVolumioState Jul 10 17:18:06 volumio volumio[1024]: info: CoreStateMachine::getcurrentVolume Jul 10 17:18:06 volumio volumio[1024]: info: CoreCommandRouter::volumioRetrievevolume Jul 10 17:18:06 volumio volumio[1024]: info: CoreStateMachine::updateTrackBlock Jul 10 17:18:06 volumio volumio[1024]: info: CorePlayQueue::getTrackBlock Jul 10 17:18:06 volumio volumio[1085]: 2025-07-10 17:18:06 [SPTF] 17:18:06.781 E [ap:3953] Connection error: 410 Jul 10 17:18:06 volumio volumio[1085]: 2025-07-10 17:18:06 [WARN] Login failed: Bad username and/or password Jul 10 17:18:06 volumio volumio[1085]: 2025-07-10 17:18:06 [ERR ] Could not get the playlist container. Jul 10 17:18:06 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Jul 10 17:18:06 volumio systemd[1]: Unit spop.service entered failed state. Jul 10 17:18:06 volumio volumio[1093]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Jul 10 17:18:06 volumio volumio[1024]: info: Setting Device type: Raspberry PI Jul 10 17:18:06 volumio volumio[1024]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 10 17:18:06 volumio volumio[1024]: SpotifyConnect: 739.447ms Jul 10 17:18:06 volumio volumio[1024]: info: SpopD Daemon Started Jul 10 17:18:06 volumio volumio[1024]: info: Data script executed Jul 10 17:18:06 volumio volumio[1024]: info: MPD running with PID578 ,establishing connection Jul 10 17:18:07 volumio volumio[1024]: info: VolumeController:: Volume=48 Mute =false Jul 10 17:18:07 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:07 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:07 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 10 17:18:07 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:07 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:07 volumio volumio[1024]: info: Reloading queue from file Jul 10 17:18:07 volumio volumio[1024]: info: Jul 10 17:18:07 volumio volumio[1024]: ---------------------------- Client requests Volume 90 Jul 10 17:18:07 volumio volumio[1024]: info: VolumeController::SetAlsaVolume90 Jul 10 17:18:07 volumio volumio[1024]: info: Jul 10 17:18:07 volumio volumio[1024]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Jul 10 17:18:07 volumio volumio[1024]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Jul 10 17:18:07 volumio volumio[1024]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Jul 10 17:18:07 volumio volumio[1024]: at emitOne (events.js:116:13) Jul 10 17:18:07 volumio volumio[1024]: at Socket.emit (events.js:211:7) Jul 10 17:18:07 volumio volumio[1024]: at addChunk (_stream_readable.js:263:12) Jul 10 17:18:07 volumio volumio[1024]: at readableAddChunk (_stream_readable.js:246:13) Jul 10 17:18:07 volumio volumio[1024]: at Socket.Readable.push (_stream_readable.js:208:10) Jul 10 17:18:07 volumio volumio[1024]: at Pipe.onread (net.js:607:20) Jul 10 17:18:07 volumio volumio[1024]: info: CoreStateMachine::setRepeat null single undefined Jul 10 17:18:07 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:07 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:07 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 10 17:18:07 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:07 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:07 volumio volumio[1024]: info: CoreStateMachine::setRandom null Jul 10 17:18:07 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:07 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:07 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 10 17:18:07 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:07 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:07 volumio volumio[1024]: null Jul 10 17:18:07 volumio volumio[1024]: info: VolumeController::Volume 90 Jul 10 17:18:07 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:07 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:07 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 10 17:18:07 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:07 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:07 volumio volumio[1024]: info: Setting volume on startup at 90 Jul 10 17:18:07 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 10 17:18:07 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 10 17:18:07 volumio sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Jul 10 17:18:07 volumio volumio[1024]: Discovery: adding 424defd2-8a69-4c23-8e2d-9bce2c9a9e21 Jul 10 17:18:07 volumio volumio[1024]: info: mDNS: Found device Volumio Jul 10 17:18:07 volumio sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:18:07 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Jul 10 17:18:07 volumio systemd[1]: Started ShairportSync AirTunes receiver. Jul 10 17:18:07 volumio sudo[1122]: pam_unix(sudo:session): session closed for user root Jul 10 17:18:07 volumio volumio[1024]: info: [1752167887786] Shairport-Sync Started Jul 10 17:18:07 volumio volumio[1024]: Error adding Membership: Error: addMembership EINVAL Jul 10 17:18:07 volumio shairport-sync[1129]: Successful Startup Jul 10 17:18:09 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 10 17:18:09 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:09 volumio volumio[1024]: info: CorePlayQueue::getTrack 0 Jul 10 17:18:09 volumio sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 10 17:18:09 volumio sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:18:09 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Jul 10 17:18:09 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 10 17:18:09 volumio sudo[1140]: pam_unix(sudo:session): session closed for user root Jul 10 17:18:09 volumio volumio[1024]: info: Upmpdcli Daemon Started Jul 10 17:18:10 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.202:5353): type 47 is invalid Jul 10 17:18:11 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.205:5353): type 47 is invalid Jul 10 17:18:11 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:11 volumio volumio[1024]: info: Connecting to daemon Jul 10 17:18:11 volumio volumio[1024]: error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602 Jul 10 17:18:11 volumio volumio[1024]: error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602 Jul 10 17:18:11 volumio volumio[1024]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jul 10 17:18:12 volumio kernel: Voltage normalised (0x00000000) Jul 10 17:18:13 volumio volumio[1024]: info: CoreCommandRouter::volumioStop Jul 10 17:18:13 volumio volumio[1024]: info: CoreStateMachine::stop Jul 10 17:18:13 volumio volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 17:18:13 volumio volumio[1024]: UNSET VOLATILE Jul 10 17:18:13 volumio volumio[1024]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 17:18:13 volumio volumio[1024]: info: Airplay started streaming Jul 10 17:18:13 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 10 17:18:13 volumio kernel: snd-rpi-iqaudio-dac soc:sound: snd_rpi_iqaudio_gpio_unmute: un-muting amp using GPIO22 Jul 10 17:18:13 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.203:5353): type 47 is invalid Jul 10 17:18:14 volumio volumio-remote-updater[544]: [2025-07-10 17:18:14] [connect] Successful connection Jul 10 17:18:15 volumio volumio-remote-updater[544]: [2025-07-10 17:18:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1752167894 101 Jul 10 17:18:15 volumio volumio[1024]: info: CoreCommandRouter::servicePushState Jul 10 17:18:15 volumio volumio[1024]: info: CoreStateMachine::syncState Jul 10 17:18:15 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:15 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:15 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:15 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:15 volumio volumio[1024]: info: CoreCommandRouter::servicePushState Jul 10 17:18:15 volumio volumio[1024]: info: CoreStateMachine::syncState Jul 10 17:18:15 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:15 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:15 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:15 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:15 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.201:5353): type 47 is invalid Jul 10 17:18:16 volumio volumio[1024]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.452&uuid=61885421d87bc21289712beb2ef4672c" http://updates.volumio.org:7070/downloader-v1/track-device Jul 10 17:18:16 volumio volumio[1024]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 10 17:18:16 volumio volumio[1024]: Dload Upload Total Spent Left Speed Jul 10 17:18:16 volumio volumio[1024]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jul 10 17:18:16 volumio volumio[1024]: retrying in 5 seconds, trial 0 Jul 10 17:18:16 volumio volumio[1024]: Volumio Calling Home Jul 10 17:18:17 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:18 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.206:5353): type 47 is invalid Jul 10 17:18:18 volumio volumio[1024]: info: CoreCommandRouter::servicePushState Jul 10 17:18:18 volumio volumio[1024]: info: CoreStateMachine::syncState Jul 10 17:18:18 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:18 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:18 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:18 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:19 volumio volumio[1024]: info: CoreCommandRouter::servicePushState Jul 10 17:18:19 volumio volumio[1024]: info: CoreStateMachine::syncState Jul 10 17:18:19 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:19 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:19 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:19 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:19 volumio volumio[1024]: info: CoreCommandRouter::servicePushState Jul 10 17:18:19 volumio volumio[1024]: info: CoreStateMachine::syncState Jul 10 17:18:19 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:19 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:19 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:19 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:22 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:26 volumio volumio[1024]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.452&uuid=61885421d87bc21289712beb2ef4672c" http://updates.volumio.org:7070/downloader-v1/track-device Jul 10 17:18:26 volumio volumio[1024]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 10 17:18:26 volumio volumio[1024]: Dload Upload Total Spent Left Speed Jul 10 17:18:26 volumio volumio[1024]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jul 10 17:18:26 volumio volumio[1024]: retrying in 5 seconds, trial 1 Jul 10 17:18:26 volumio volumio[1024]: Volumio Calling Home Jul 10 17:18:28 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:32 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.204:5353): type 47 is invalid Jul 10 17:18:33 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:36 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 17:18:36 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 17:18:37 volumio volumio[1024]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.452&uuid=61885421d87bc21289712beb2ef4672c" http://updates.volumio.org:7070/downloader-v1/track-device Jul 10 17:18:37 volumio volumio[1024]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 10 17:18:37 volumio volumio[1024]: Dload Upload Total Spent Left Speed Jul 10 17:18:37 volumio volumio[1024]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jul 10 17:18:37 volumio volumio[1024]: retrying in 5 seconds, trial 2 Jul 10 17:18:37 volumio volumio[1024]: Volumio Calling Home Jul 10 17:18:38 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.202:5353): type 47 is invalid Jul 10 17:18:39 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:40 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.205:5353): type 47 is invalid Jul 10 17:18:42 volumio volumio[1024]: info: CoreCommandRouter::servicePushState Jul 10 17:18:42 volumio volumio[1024]: info: CoreStateMachine::syncState Jul 10 17:18:42 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:42 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:42 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:42 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:42 volumio volumio[1024]: info: CoreCommandRouter::servicePushState Jul 10 17:18:42 volumio volumio[1024]: info: CoreStateMachine::syncState Jul 10 17:18:42 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:42 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:42 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:42 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:43 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.203:5353): type 47 is invalid Jul 10 17:18:44 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:44 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.201:5353): type 47 is invalid Jul 10 17:18:46 volumio volumio[1093]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.206:5353): type 47 is invalid Jul 10 17:18:50 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:55 volumio volumio[1093]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Jul 10 17:18:55 volumio wpa_supplicant[830]: wlan0: Failed to initiate sched scan Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Authenticated as "raph.dago" ! Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Setting up new mixer: card:hw:1 mixer:Digital index:0 Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-9999999)[dB]) -- max: 207 (MilliBel(0)[dB]) HW: true Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Using alsa sink Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Metadata pipe established Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Country: "BE" Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Event: Volume { volume_to_mixer: 58981 } Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Fetching autoplay context uri Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Event: SessionActive { became_active_at: 1752167936278 } Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : SessionActive! Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 156101128123359639621850216550973717727, audio_type: Track } } Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 156101128123359639621850216550973717727, audio_type: Track } } Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : No autoplay_uri found Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : AutoplayError: MercuryError Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCF-V2q72ruGW5usMQgztKzev_UWWLxreawe5SBUMH6hQkzSpMeUYsgU8xMLPNUECtFXMR7LCNKSTzL1tb3C_CPWurpI1mTBH_qCOZQLwSFw8_vbUB2eTYS18WiNOQArYBxYmbLlDV32u0rIYcP_lpgV27GkRxacvMPAp-YQ-6omHPrB5bnVrYvySrzW7EJrkrE_piBPJmlo96Oco0uFmpHo43dRWjuLUeWJTusZS2X", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-modify-playback-state", "user-read-playback-state", "user-read-currently-playing", "user-read-private"] } } Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Loading <(Sittin' On) the Dock of the Bay> with Spotify URI Jul 10 17:18:56 volumio volumio[1024]: [SpotifyConnect] 89.99923704890517 Jul 10 17:18:56 volumio volumio[1024]: [SpotifyConnect] Volume: Spotify:89.99923704890517 Volumio: 90 Jul 10 17:18:56 volumio volumio[1024]: info: CoreStateMachine::pushState Jul 10 17:18:56 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:56 volumio volumio[1024]: info: CoreCommandRouter::volumioPushState Jul 10 17:18:56 volumio volumio[1024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:18:56 volumio volumio[1024]: [SpotifyConnect] A connect session has begun Jul 10 17:18:56 volumio volumio[1024]: [SpotifyConnect] Device palyback is active! Jul 10 17:18:56 volumio volumio[1024]: info: CoreCommandRouter::volumioGetState Jul 10 17:18:56 volumio volumio[1024]: info: CoreStateMachine::getState Jul 10 17:18:56 volumio volumio[1024]: [SpotifyConnect] Currently active: airplay Jul 10 17:18:56 volumio volumio[1024]: [SpotifyConnect] Stopping currently active service Jul 10 17:18:56 volumio volumio[1024]: info: CoreCommandRouter::volumioStop Jul 10 17:18:56 volumio volumio[1024]: info: CoreStateMachine::stop Jul 10 17:18:56 volumio volumio[1024]: info: CoreStateMachine::serviceStop Jul 10 17:18:56 volumio volumio[1024]: info: CoreCommandRouter::serviceStop Jul 10 17:18:56 volumio volumio[1024]: error: WARNING: No stop method for service airplay Jul 10 17:18:56 volumio volumio[1024]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 17:18:56 volumio volumio[1024]: TypeError: Cannot read property 'then' of undefined Jul 10 17:18:56 volumio volumio[1024]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) Jul 10 17:18:56 volumio volumio[1024]: at emitNone (events.js:106:13) Jul 10 17:18:56 volumio volumio[1024]: at SpotConnEvents.emit (events.js:208:7) Jul 10 17:18:56 volumio volumio[1024]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Jul 10 17:18:56 volumio volumio[1024]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Jul 10 17:18:56 volumio volumio[1024]: at emitTwo (events.js:126:13) Jul 10 17:18:56 volumio volumio[1024]: at Socket.emit (events.js:214:7) Jul 10 17:18:56 volumio volumio[1024]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Jul 10 17:18:56 volumio volumio[1024]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : <(Sittin' On) the Dock of the Bay> (163755 ms) loaded Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Jul 10 17:18:56 volumio volumio[1093]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Jul 10 17:18:56 volumio volumio[1093]: thread '' panicked at 'called `Option::unwrap()` on a `None` value', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/audio_backend/alsa.rs:129:35 Jul 10 17:18:56 volumio volumio[1093]: stack backtrace: Jul 10 17:18:57 volumio volumio[1093]: 0: 0x9f533c - backtrace::backtrace::libunwind::trace::hae21a072c81e5842 Jul 10 17:18:57 volumio volumio[1093]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 Jul 10 17:18:57 volumio volumio[1093]: 1: 0x9f533c - backtrace::backtrace::trace_unsynchronized::h0f9b260087e46e47 Jul 10 17:18:57 volumio volumio[1093]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 Jul 10 17:18:57 volumio volumio[1093]: 2: 0x9f533c - std::sys_common::backtrace::_print_fmt::hbf1a59173a7860c3 Jul 10 17:18:57 volumio volumio[1093]: at src/libstd/sys_common/backtrace.rs:78 Jul 10 17:18:57 volumio volumio[1093]: 3: 0x9f533c - ::fmt::he1a5d6f378e506c4 Jul 10 17:18:57 volumio volumio[1093]: at src/libstd/sys_common/backtrace.rs:59 Jul 10 17:18:57 volumio volumio[1093]: 4: 0xa15d5c - core::fmt::write::hb37ae5a5e0b70623 Jul 10 17:18:57 volumio volumio[1093]: at src/libcore/fmt/mod.rs:1076 Jul 10 17:18:57 volumio volumio[1093]: 5: 0x9eec64 - std::io::Write::write_fmt::ha24bb3f5a858327b Jul 10 17:18:57 volumio volumio[1093]: at src/libstd/io/mod.rs:1537 Jul 10 17:18:57 volumio volumio[1093]: 6: 0x9f7974 - std::sys_common::backtrace::_print::h47b03aa1342833e3 Jul 10 17:18:57 volumio volumio[1093]: at src/libstd/sys_common/backtrace.rs:62 Jul 10 17:18:57 volumio volumio[1093]: 7: 0x9f7974 - std::sys_common::backtrace::print::h2217cbc390250439 Jul 10 17:18:57 volumio volumio[1093]: at src/libstd/sys_common/backtrace.rs:49 Jul 10 17:18:57 volumio volumio[1093]: 8: 0x9f7974 - std::panicking::default_hook::{{closure}}::h179f5229ea1c8e30 Jul 10 17:18:57 volumio volumio[1093]: at src/libstd/panicking.rs:198 Jul 10 17:18:57 volumio volumio[1093]: 9: 0x9f7640 - std::panicking::default_hook::h46ab82039cbc65eb Jul 10 17:18:57 volumio volumio[1093]: at src/libstd/panicking.rs:217 Jul 10 17:18:57 volumio volumio[1093]: 10: 0x9f8054 - std::panicking::rust_panic_with_hook::h7326c48419bc7c33 Jul 10 17:18:57 volumio volumio[1093]: at src/libstd/panicking.rs:526 Jul 10 17:18:57 volumio volumio[1093]: 11: 0x9f7c54 - rust_begin_unwind Jul 10 17:18:57 volumio volumio[1093]: at src/libstd/panicking.rs:437 Jul 10 17:18:57 volumio volumio[1093]: 12: 0xa13594 - core::panicking::panic_fmt::ha292e19d5ae716ed Jul 10 17:18:57 volumio volumio[1093]: at src/libcore/panicking.rs:85 Jul 10 17:18:57 volumio volumio[1093]: 13: 0xa134f0 - core::panicking::panic::heb457f4fa750842b Jul 10 17:18:57 volumio volumio[1093]: at src/libcore/panicking.rs:50 Jul 10 17:18:57 volumio volumio[1093]: 14: 0x5f2ee8 - ::write::h5da62cd8da1289c3 Jul 10 17:18:57 volumio volumio[1093]: 15: 0x5cc3c4 - ::poll::h2bafd5dc8d40bf3f Jul 10 17:18:57 volumio volumio[1093]: 16: 0x4dceec - futures::task_impl::std::set::h2116cb0e9a5165fe Jul 10 17:18:57 volumio volumio[1093]: 17: 0x4d1c6c - futures::task_impl::Spawn::poll_future_notify::h20a5cc82ac41f2fa Jul 10 17:18:57 volumio volumio[1093]: 18: 0x4eb6a0 - futures::future::Future::wait::ha8819dd7a48bc248 Jul 10 17:18:57 volumio volumio[1093]: 19: 0x4d34b4 - std::sys_common::backtrace::__rust_begin_short_backtrace::habfd768f2a80dc30 Jul 10 17:18:57 volumio volumio[1093]: 20: 0x4f25a8 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0dcfa92cb8b4ff47 Jul 10 17:18:57 volumio volumio[1093]: 21: 0x9fb46c - as core::ops::function::FnOnce>::call_once::hb2bcc0b96e754d10 Jul 10 17:18:57 volumio volumio[1093]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Jul 10 17:18:57 volumio volumio[1093]: 22: 0x9fb46c - as core::ops::function::FnOnce>::call_once::h2dd73f7c66408260 Jul 10 17:18:57 volumio volumio[1093]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Jul 10 17:18:57 volumio volumio[1093]: 23: 0x9fb46c - std::sys::unix::thread::Thread::new::thread_start::h6fc0046e32ed87dc Jul 10 17:18:57 volumio volumio[1093]: at src/libstd/sys/unix/thread.rs:87 Jul 10 17:18:58 volumio sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-10 17:17 Jul 10 17:18:58 volumio sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="7124eaf0213deb060cebb17ef631669189a6650e" VOLUMIO_FE_VERSION="b52b5dfa93e3b26d6f23f09c91a5b90b0a43edf0" VOLUMIO_BE_VERSION="273d2050e44b6f5ae901d967e346577f7ad86f2c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Aug 27 17:21:50 CEST 2018" VOLUMIO_VERSION="2.452" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="1802c403029d39af3072f40e987e7ba4"