Feb 26 02:23:07 volumiopi5 volumiossh-tunnel[17300]: ssh: Could not resolve hostname eu4.myvolumio.org: Device or resource busy Feb 26 02:23:07 volumiopi5 autossh[2369]: ssh exited with error status 255; restarting ssh Feb 26 02:23:07 volumiopi5 volumio5-onboarding[1638]: time=2026-02-26T02:23:07.360+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="read tcp 127.0.0.1:59358->127.0.0.1:3000: read: connection reset by peer" Feb 26 02:23:07 volumiopi5 volumio-remote-updater[978]: [2026-02-26 02:23:07] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 26 02:23:07 volumiopi5 volumio-remote-updater[978]: [2026-02-26 02:23:07] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 26 02:23:07 volumiopi5 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:23:07 volumiopi5 volumio5-onboarding[1638]: time=2026-02-26T02:23:07.362+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:55082->127.0.0.1:3000: read: connection reset by peer" Feb 26 02:23:07 volumiopi5 systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 26 02:23:07 volumiopi5 systemd[1]: volumio.service: Consumed 16.214s CPU time. Feb 26 02:23:07 volumiopi5 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 26 02:23:07 volumiopi5 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 26 02:23:07 volumiopi5 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Feb 26 02:23:07 volumiopi5 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 26 02:23:07 volumiopi5 systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 26 02:23:07 volumiopi5 systemd[1]: volumio.service: Consumed 16.214s CPU time. Feb 26 02:23:07 volumiopi5 systemd[1]: Started volumio.service - Volumio Backend Module. Feb 26 02:23:07 volumiopi5 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 26 02:23:08 volumiopi5 volumio[17351]: info: ------------------------------------------- Feb 26 02:23:08 volumiopi5 volumio[17351]: info: ----- Volumio3 ---- Feb 26 02:23:08 volumiopi5 volumio[17351]: info: ------------------------------------------- Feb 26 02:23:08 volumiopi5 volumio[17351]: info: ----- System startup ---- Feb 26 02:23:08 volumiopi5 volumio[17351]: info: ------------------------------------------- Feb 26 02:23:08 volumiopi5 volumio[17351]: info: MYVOLUMIO Environment detected Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Plugin folders cleanup Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning into folder /volumio/app/plugins/ Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning category audio_interface Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning category miscellanea Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning category music_service Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning category plugins.json Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning category system_controller Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning category user_interface Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning into folder /data/plugins/ Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning category music_service Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning category system_controller Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning category system_hardware Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Scanning category user_interface Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Plugin folders cleanup completed Feb 26 02:23:08 volumiopi5 volumio[17351]: info: ------------------------------------------- Feb 26 02:23:08 volumiopi5 volumio[17351]: info: ----- Core plugins startup ---- Feb 26 02:23:08 volumiopi5 volumio[17351]: info: ------------------------------------------- Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugins from folder /volumio/app/plugins/ Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Adding plugin upnp to MyMusic Plugins Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugins from folder /data/plugins/ Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugin "system"... Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugin "appearance"... Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugin "network"... Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Refreshing Cached IP Addresses Feb 26 02:23:08 volumiopi5 sudo[17379]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 02:23:08 volumiopi5 sudo[17379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:08 volumiopi5 sudo[17381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 02:23:08 volumiopi5 sudo[17381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:08 volumiopi5 sudo[17379]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:08 volumiopi5 sudo[17381]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugin "services"... Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugin "volumio5onboarding"... Feb 26 02:23:08 volumiopi5 sudo[17387]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 26 02:23:08 volumiopi5 sudo[17387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugin "alsa_controller"... Feb 26 02:23:08 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugin "wizard"... Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugin "networkfs"... Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Starting Udev Watcher for removable devices Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Ignoring mount for partition: boot Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Ignoring mount for partition: volumio Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Ignoring mount for partition: volumio_data Feb 26 02:23:08 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 02:23:08 volumiopi5 volumio[17351]: info: Loading plugin "pi_screen_setup"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "volumio_command_line_client"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "upnp"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: [1772068989102] Starting Upmpd Daemon Feb 26 02:23:09 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "my_music"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "mpd"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Plugin upnp_browser is not enabled Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "alarm-clock"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "airplay_emulation"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Starting Shairport Sync Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "last_100"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "webradio"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "i2s_dacs"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "volumiodiscovery"... Feb 26 02:23:09 volumiopi5 volumio[17351]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 02:23:09 volumiopi5 volumio[17351]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 02:23:09 volumiopi5 volumio[17351]: *** WARNING *** For more information see Feb 26 02:23:09 volumiopi5 volumio[17351]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 02:23:09 volumiopi5 volumio[17351]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 02:23:09 volumiopi5 node[17351]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 02:23:09 volumiopi5 volumio[17351]: *** WARNING *** For more information see Feb 26 02:23:09 volumiopi5 node[17351]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 02:23:09 volumiopi5 node[17351]: *** WARNING *** For more information see Feb 26 02:23:09 volumiopi5 node[17351]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 02:23:09 volumiopi5 node[17351]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 02:23:09 volumiopi5 node[17351]: *** WARNING *** For more information see Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Discovery: Started advertising with name: Volumiopi5 Feb 26 02:23:09 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "spop"... Feb 26 02:23:09 volumiopi5 volumio[17351]: info: Loading plugin "audiophonicsonoff"... Feb 26 02:23:10 volumiopi5 volumio[17351]: gpiox_arm32 V2.2.1 Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "gpio-buttons"... Feb 26 02:23:10 volumiopi5 volumio[17351]: gpiox_arm32 V2.2.1 Feb 26 02:23:10 volumiopi5 volumio[17351]: info: gpio-buttons: Initialized Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "gpio_button_led"... Feb 26 02:23:10 volumiopi5 volumio[17351]: gpiox_arm32 V2.2.1 Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "now_playing"... Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "outputs"... Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "albumart"... Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Plugin example_plugin is not enabled Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "inputs"... Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "updater_comm"... Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Plugin mpdemulation is not enabled Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "rest_api"... Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "websocket"... Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Starting Socket.io Server version 1.7.4 Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "rpi_eeprom_config"... Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Applying required configuration parameters for plugin rpi_eeprom_config Feb 26 02:23:10 volumiopi5 volumio[17351]: info: [RpiEepromConfig] Hardware capabilities loaded Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "mpd_oled"... Feb 26 02:23:10 volumiopi5 volumio[17411]: Forking 3 albumart workers Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Applying required configuration parameters for plugin mpd_oled Feb 26 02:23:10 volumiopi5 volumio[17351]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "Systeminfo"... Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading plugin "touch_display"... Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Applying required configuration parameters for plugin touch_display Feb 26 02:23:10 volumiopi5 volumio[17351]: info: Loading i18n strings for locale fr Feb 26 02:23:10 volumiopi5 volumio[17351]: Updating browse sources language Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::initPlayerControls Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 02:23:10 volumiopi5 volumio[17351]: Express server listening on port 3000 Feb 26 02:23:10 volumiopi5 volumio[17351]: [Metrics] WebUI: 2s 807.44ms Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreStateMachine::resetVolumioState Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreStateMachine::getcurrentVolume Feb 26 02:23:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Volumio Network Manager: Network status updated: 1 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreStateMachine::pushState Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioPushState Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreStateMachine::updateTrackBlock Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrackBlock Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 26 02:23:11 volumiopi5 volumio[17423]: Starting albumart workers Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Reloading queue from file Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreStateMachine::pushState Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioPushState Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreStateMachine::setRepeat null single undefined Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreStateMachine::pushState Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioPushState Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreStateMachine::setRandom null Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreStateMachine::pushState Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioPushState Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Setting Device type: Raspberry PI Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Completed loading Core Plugins Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Preparing to generate the ALSA configuration file Feb 26 02:23:11 volumiopi5 volumio[17351]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 26 02:23:11 volumiopi5 volumio[17351]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 26 02:23:11 volumiopi5 volumio[17421]: Starting albumart workers Feb 26 02:23:11 volumiopi5 volumio[17351]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 26 02:23:11 volumiopi5 volumio[17351]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Reading ALSA contributions from plugins. Feb 26 02:23:11 volumiopi5 volumio[17422]: Starting albumart workers Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: gpio-buttons: Socket connected to Volumio Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Asound.conf file unchanged, so no further update is needed Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Output device has changed, restarting MPD Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Output device has changed, restarting Shairport Sync Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:11 volumiopi5 sudo[17480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 02:23:11 volumiopi5 sudo[17480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 sudo[17478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 02:23:11 volumiopi5 sudo[17478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: ___________ START PLUGINS ___________ Feb 26 02:23:11 volumiopi5 sudo[17478]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:11 volumiopi5 volumio[17351]: info: pi_screen_setup: Starting plugin Feb 26 02:23:11 volumiopi5 systemd[1]: Stopping mpd_oled_plugin.service - MPD OLED Plugin... Feb 26 02:23:11 volumiopi5 sudo[17488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 26 02:23:11 volumiopi5 sudo[17488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 volumio[17351]: info: pi_screen_setup: Loaded bundled presets v1.5.5 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: pi_screen_setup: Active presets source: bundled, 224 presets loaded Feb 26 02:23:11 volumiopi5 volumio[17351]: info: pi_screen_setup: Detected model: Raspberry Pi 5 Model B Rev 1.0 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: pi_screen_setup: Detected SoC: bcm2712 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: pi_screen_setup: Detected RAM: 3997 MB Feb 26 02:23:11 volumiopi5 volumio[17351]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 5","model_raw":"Raspberry Pi 5 Model B Rev 1.0","soc":"bcm2712","ram_mb":3997,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi5","is_pi":true} Feb 26 02:23:11 volumiopi5 volumio[17351]: info: ControllerMpd::onStart: Initializing MPD Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Creating MPD Configuration file Feb 26 02:23:11 volumiopi5 systemd[1]: mpd_oled_plugin.service: Deactivated successfully. Feb 26 02:23:11 volumiopi5 systemd[1]: Stopped mpd_oled_plugin.service - MPD OLED Plugin. Feb 26 02:23:11 volumiopi5 systemd[1]: mpd_oled_plugin.service: Consumed 1.939s CPU time. Feb 26 02:23:11 volumiopi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 26 02:23:11 volumiopi5 sudo[17488]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:11 volumiopi5 sudo[17494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 02:23:11 volumiopi5 sudo[17494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 sudo[17494]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:11 volumiopi5 sudo[17499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 02:23:11 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Feb 26 02:23:11 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 26 02:23:11 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Feb 26 02:23:11 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 26 02:23:11 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 26 02:23:11 volumiopi5 sudo[17499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 26 02:23:11 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:11 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Feb 26 02:23:11 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 26 02:23:11 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Feb 26 02:23:11 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 26 02:23:11 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [1772068991434] CoreMusicLibrary::Adding element Last_100 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [1772068991438] CoreMusicLibrary::Adding element Webradio Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 02:23:11 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 26 02:23:11 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Initializing BBC Radios Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Creating Spotify config file Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 sudo[17518]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 02:23:11 volumiopi5 sudo[17518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 02:23:11 volumiopi5 sudo[17518]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:11 volumiopi5 sudo[17387]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:11 volumiopi5 volumio[17351]: info: AudiophonicsOnOff: Starting plugin Feb 26 02:23:11 volumiopi5 volumio[17351]: info: AudiophonicsOnOff: Configuring GPIO pins Feb 26 02:23:11 volumiopi5 volumio[17351]: info: AudiophonicsOnOff: Soft shutdown GPIO 4 initialized Feb 26 02:23:11 volumiopi5 volumio[17351]: info: AudiophonicsOnOff: Boot OK GPIO 22 set HIGH Feb 26 02:23:11 volumiopi5 volumio[17351]: info: AudiophonicsOnOff: Shutdown button GPIO 17 watching (PULLDOWN, RISING edge) Feb 26 02:23:11 volumiopi5 volumio[17351]: info: AudiophonicsOnOff: Plugin started successfully Feb 26 02:23:11 volumiopi5 volumio[17351]: info: gpio-buttons: Creating GPIO triggers... Feb 26 02:23:11 volumiopi5 volumio[17351]: info: GPIOButtonLED: Starting plugin Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [now-playing] ConfigUpdater: config is up to date. Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [RpiEepromConfig] Starting Raspberry Pi EEPROM Configuration Manager Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [RpiEepromConfig] Detected full model string: Raspberry Pi 5 Model B Rev 1.0 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [RpiEepromConfig] Matched to capability profile: Raspberry Pi 5 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [RpiEepromConfig] Detected model: Raspberry Pi 5 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [RpiEepromConfig] Boot modes: sd, usb, nvme Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [RpiEepromConfig] Using rpi-eeprom-config at: /usr/bin/rpi-eeprom-config Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [RpiEepromConfig] Backup directory ensured: /data/configuration/system_controller/rpi_eeprom_config/backup Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [RpiEepromConfig] Plugin started successfully Feb 26 02:23:11 volumiopi5 volumio[17351]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Feb 26 02:23:11 volumiopi5 volumio[17351]: error: [MPD_OLED Plugin] Failed to create mpdoledfifo: Error: Command failed: /usr/bin/mkfifo -m 646 /tmp/mpdoledfifo Feb 26 02:23:11 volumiopi5 volumio[17351]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Loading i18n strings for locale fr Feb 26 02:23:11 volumiopi5 sudo[17547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Feb 26 02:23:11 volumiopi5 sudo[17547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 sudo[17548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Feb 26 02:23:11 volumiopi5 sudo[17547]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:11 volumiopi5 sudo[17548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Volumio Calling Home Feb 26 02:23:11 volumiopi5 sudo[17553]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 26 02:23:11 volumiopi5 sudo[17553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 sudo[17553]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:11 volumiopi5 volumio[17351]: info: gpio-buttons: Started successfully Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Preparing to generate the ALSA configuration file Feb 26 02:23:11 volumiopi5 volumio[17351]: info: pi_screen_setup: Management server started on port 4567 Feb 26 02:23:11 volumiopi5 sudo[17559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 26 02:23:11 volumiopi5 sudo[17559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 sudo[17561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 26 02:23:11 volumiopi5 sudo[17561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 sudo[17563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 26 02:23:11 volumiopi5 sudo[17563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 systemd[1]: Reloading. Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [now-playing] App is listening on port 4004. Feb 26 02:23:11 volumiopi5 volumio[17351]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 26 02:23:11 volumiopi5 volumio[17351]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Reading ALSA contributions from plugins. Feb 26 02:23:11 volumiopi5 volumio[17351]: info: touch_display: No backlight interface detected. Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Feb 26 02:23:11 volumiopi5 volumio[17351]: info: MPD Permissions set Feb 26 02:23:11 volumiopi5 volumio[17351]: info: MPD Permissions set Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Spotify config file written Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 sudo[17570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 sudo[17570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 sudo[17574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 26 02:23:11 volumiopi5 sudo[17574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 sudo[17570]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:11 volumiopi5 volumio[17351]: info: No need to fix Spotify hosts Feb 26 02:23:11 volumiopi5 volumio[17351]: info: GPIOButtonLED: dtoverlay already correct Feb 26 02:23:11 volumiopi5 volumio[17351]: info: GPIOButtonLED: LED+ on GPIO 22 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: GPIOButtonLED: LED- on GPIO 4 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: GPIOButtonLED: LED polarity = normal Feb 26 02:23:11 volumiopi5 volumio[17351]: info: GPIOButtonLED: LED blink slow Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 26 02:23:11 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Starting Shairport Sync Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Starting Shairport Sync Feb 26 02:23:11 volumiopi5 volumio[17351]: info: Starting Shairport Sync Feb 26 02:23:11 volumiopi5 sudo[17605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 02:23:11 volumiopi5 sudo[17605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:11 volumiopi5 volumio[17351]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 26 02:23:11 volumiopi5 sudo[17610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 02:23:11 volumiopi5 sudo[17610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:12 volumiopi5 sudo[17607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 02:23:12 volumiopi5 sudo[17607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 02:23:12 volumiopi5 sudo[17614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 26 02:23:12 volumiopi5 sudo[17614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:12 volumiopi5 sudo[17612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 26 02:23:12 volumiopi5 sudo[17614]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:12 volumiopi5 sudo[17612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:12 volumiopi5 sudo[17612]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 02:23:12 volumiopi5 volumio[17351]: info: Received Get System Info Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 02:23:12 volumiopi5 volumio[17351]: info: Discovery: Getting this device information Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:12 volumiopi5 volumio[17351]: info: Listing playlists Feb 26 02:23:12 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 26 02:23:12 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 26 02:23:12 volumiopi5 volumio[17351]: info: Received Get System Info Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 02:23:12 volumiopi5 volumio[17351]: info: Discovery: Getting this device information Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 02:23:12 volumiopi5 volumio[17351]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 26 02:23:12 volumiopi5 volumio[17351]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 26 02:23:12 volumiopi5 volumio[17351]: info: Asound.conf file unchanged, so no further update is needed Feb 26 02:23:12 volumiopi5 volumio[17351]: info: Output device has changed, restarting MPD Feb 26 02:23:12 volumiopi5 volumio[17351]: info: Output device has changed, restarting Shairport Sync Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:12 volumiopi5 sudo[17620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 02:23:12 volumiopi5 sudo[17620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:12 volumiopi5 sudo[17622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 02:23:12 volumiopi5 sudo[17620]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:12 volumiopi5 sudo[17622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:12 volumiopi5 volumio[17351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 02:23:12 volumiopi5 volumio[17351]: info: MPD Permissions set Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:12 volumiopi5 volumio[17351]: info: Starting Shairport Sync Feb 26 02:23:12 volumiopi5 sudo[17635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 26 02:23:12 volumiopi5 sudo[17635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:12 volumiopi5 mpd[17541]: 2026-02-26T02:23:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 02:23:12 volumiopi5 volumio-remote-updater[978]: [2026-02-26 02:23:12] [connect] Successful connection Feb 26 02:23:12 volumiopi5 volumio-remote-updater[978]: [2026-02-26 02:23:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1772068992 101 Feb 26 02:23:12 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 6 Feb 26 02:23:12 volumiopi5 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:23:12 volumiopi5 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:23:12 volumiopi5 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:23:12 volumiopi5 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:23:12 volumiopi5 systemd[1]: Started mpd.service - Music Player Daemon. Feb 26 02:23:12 volumiopi5 sudo[17563]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:12 volumiopi5 sudo[17499]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:12 volumiopi5 sudo[17480]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:12 volumiopi5 systemd[1]: Reloading. Feb 26 02:23:12 volumiopi5 volumio[17351]: info: touch_display: systemctl daemon-reload succeeded. Feb 26 02:23:12 volumiopi5 sudo[17640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 26 02:23:12 volumiopi5 sudo[17640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:12 volumiopi5 volumio[17351]: error: MPD error: The expression evaluated to a falsy value: Feb 26 02:23:12 volumiopi5 volumio[17351]: assert.ok(self.idling) Feb 26 02:23:12 volumiopi5 volumio[17351]: error: The expression evaluated to a falsy value: Feb 26 02:23:12 volumiopi5 volumio[17351]: assert.ok(self.idling) Feb 26 02:23:12 volumiopi5 volumio[17351]: info: MPD running with PID17541 Feb 26 02:23:12 volumiopi5 volumio[17351]: ,establishing connection Feb 26 02:23:12 volumiopi5 volumio[17351]: error: updateQueue error: null Feb 26 02:23:12 volumiopi5 volumio[17351]: error: updateQueue error: null Feb 26 02:23:12 volumiopi5 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:23:12 volumiopi5 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:23:12 volumiopi5 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:23:12 volumiopi5 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 02:23:13 volumiopi5 sudo[17561]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 volumio[17351]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 26 02:23:13 volumiopi5 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 26 02:23:13 volumiopi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 26 02:23:13 volumiopi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 26 02:23:13 volumiopi5 sudo[17640]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 26 02:23:13 volumiopi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 02:23:13 volumiopi5 systemd[1]: shairport-sync.service: Consumed 1.664s CPU time. Feb 26 02:23:13 volumiopi5 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 26 02:23:13 volumiopi5 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:23:13 volumiopi5 volumio[17351]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 26 02:23:13 volumiopi5 volumio[17351]: info: touch_display: Volumio Kiosk started. Feb 26 02:23:13 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Feb 26 02:23:13 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 26 02:23:13 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:13 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:13 volumiopi5 volumio[17351]: info: Completed starting Core Plugins Feb 26 02:23:13 volumiopi5 volumio[17351]: info: ------------------------------------------- Feb 26 02:23:13 volumiopi5 volumio[17351]: info: ----- MyVolumio plugins startup ---- Feb 26 02:23:13 volumiopi5 volumio[17351]: info: ------------------------------------------- Feb 26 02:23:13 volumiopi5 volumio[17351]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 26 02:23:13 volumiopi5 sudo[17559]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 volumio[17351]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 26 02:23:13 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Feb 26 02:23:13 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 26 02:23:13 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 26 02:23:13 volumiopi5 volumio[17351]: info: touch_display: X display number found: 0 Feb 26 02:23:13 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 26 02:23:13 volumiopi5 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:23:13 volumiopi5 go-librespot[17664]: go-librespot daemon starting... Feb 26 02:23:13 volumiopi5 sudo[17574]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 26 02:23:13 volumiopi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 26 02:23:13 volumiopi5 go-librespot[17665]: time="2026-02-26T02:23:13+01:00" level=info msg="running go-librespot 0.6.2" Feb 26 02:23:13 volumiopi5 go-librespot[17665]: time="2026-02-26T02:23:13+01:00" level=debug msg="app state loaded" Feb 26 02:23:13 volumiopi5 sudo[17605]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 sudo[17635]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 sudo[17610]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 volumio[17351]: info: Shairport-Sync Started Feb 26 02:23:13 volumiopi5 sudo[17607]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 go-librespot[17665]: time="2026-02-26T02:23:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:23:13 volumiopi5 volumio[17351]: Error adding Membership: Error: addMembership EINVAL Feb 26 02:23:13 volumiopi5 volumio[17351]: info: Shairport-Sync Started Feb 26 02:23:13 volumiopi5 volumio[17351]: info: Shairport-Sync Started Feb 26 02:23:13 volumiopi5 volumio[17351]: info: Shairport-Sync Started Feb 26 02:23:13 volumiopi5 volumio[17351]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 26 02:23:13 volumiopi5 sudo[17666]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 02:23:13 volumiopi5 sudo[17666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 26 02:23:13 volumiopi5 sudo[17666]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 volumio[17351]: info: touch_display: X display number found: 0 Feb 26 02:23:13 volumiopi5 volumio[17351]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 26 02:23:13 volumiopi5 mpd[17692]: 2026-02-26T02:23:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 02:23:13 volumiopi5 volumio[17351]: info: GPIOButtonLED: Boot complete - LED solid Feb 26 02:23:13 volumiopi5 systemd[1]: Started mpd.service - Music Player Daemon. Feb 26 02:23:13 volumiopi5 sudo[17622]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 volumio[17351]: error: updateQueue error: null Feb 26 02:23:13 volumiopi5 systemd[1]: Started mpd_oled_plugin.service - MPD OLED Plugin. Feb 26 02:23:13 volumiopi5 sudo[17548]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:13 volumiopi5 volumio[17351]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:16 volumiopi5 volumio[17351]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 26 02:23:16 volumiopi5 volumio[17351]: info: Discovery: Restarting Browsing Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:16 volumiopi5 volumio[17351]: info: go-librespot daemon successfully initialized Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:17 volumiopi5 volumio[17351]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:19 volumiopi5 sudo[17716]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 02:23:19 volumiopi5 sudo[17716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:19 volumiopi5 sudo[17718]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 02:23:19 volumiopi5 sudo[17718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:19 volumiopi5 sudo[17716]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:19 volumiopi5 sudo[17718]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:19 volumiopi5 sudo[17722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 26 02:23:19 volumiopi5 sudo[17722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 02:23:19 volumiopi5 sudo[17722]: pam_unix(sudo:session): session closed for user root Feb 26 02:23:19 volumiopi5 volumio[17351]: info: Upmpdcli Daemon Started Feb 26 02:23:19 volumiopi5 volumio[17351]: info: Initializing connection to go-librespot Websocket Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:26 volumiopi5 volumio[17351]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 26 02:23:26 volumiopi5 volumio[17351]: info: Discovery: Restarting Browsing Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:30 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:31 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:32 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:33 volumiopi5 volumio[17351]: error: MyVolumio Plugin failed to start in a timely fashion Feb 26 02:23:33 volumiopi5 volumio[17351]: [Metrics] CommandRouter: 24s 852.80ms Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumiosetStartupVolume Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 02:23:33 volumiopi5 volumio[17351]: info: VolumeController:: Setting startup Volume 5 Feb 26 02:23:33 volumiopi5 volumio[17351]: info: VolumeController::SetAlsaVolume5 Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::Close All Modals sent Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::Close All Modals sent Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreStateMachine::pushState Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioPushState Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:33 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:33 volumiopi5 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 26 02:23:33 volumiopi5 volumio[17351]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Feb 26 02:23:33 volumiopi5 volumio[17351]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 26 02:23:33 volumiopi5 volumio[17351]: aplay: xrun:1636: status error: Broken pipe Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:34 volumiopi5 go-librespot[17665]: time="2026-02-26T02:23:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 26 02:23:34 volumiopi5 volumio[17351]: info: Discovery: adding cb270ef1-f96b-426f-8661-5552782eeb21 Feb 26 02:23:34 volumiopi5 volumio[17351]: info: Discovery: Found device Volumiopi5 Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:34 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:34 volumiopi5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:23:34 volumiopi5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:23:34 volumiopi5 volumio[17351]: info: Discovery: this is already registered, cb270ef1-f96b-426f-8661-5552782eeb21 Feb 26 02:23:35 volumiopi5 volumio[17351]: info: Discovery: Found device Volumiopi5 Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:35 volumiopi5 volumio[17351]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:35 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:36 volumiopi5 volumio[17351]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 26 02:23:36 volumiopi5 volumio[17351]: info: Discovery: Restarting Browsing Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:36 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:37 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:38 volumiopi5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 26 02:23:38 volumiopi5 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:23:38 volumiopi5 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:23:38 volumiopi5 go-librespot[17765]: go-librespot daemon starting... Feb 26 02:23:38 volumiopi5 go-librespot[17766]: time="2026-02-26T02:23:38+01:00" level=info msg="running go-librespot 0.6.2" Feb 26 02:23:38 volumiopi5 go-librespot[17766]: time="2026-02-26T02:23:38+01:00" level=debug msg="app state loaded" Feb 26 02:23:38 volumiopi5 go-librespot[17766]: time="2026-02-26T02:23:38+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:38 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:39 volumiopi5 autossh[2369]: starting ssh (count 32) Feb 26 02:23:39 volumiopi5 autossh[2369]: ssh child pid is 17787 Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:39 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:40 volumiopi5 volumio[17351]: info: BOOT COMPLETED Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:40 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:41 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:42 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:43 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:44 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:45 volumiopi5 volumio[17351]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=2f3b82c1f6cb883d485bb1a63e524f7d" http://updates.volumio.org/downloader-v1/track-device Feb 26 02:23:45 volumiopi5 volumio[17351]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 26 02:23:45 volumiopi5 volumio[17351]: Dload Upload Total Spent Left Speed Feb 26 02:23:45 volumiopi5 volumio[17351]: [1.9K blob data] Feb 26 02:23:45 volumiopi5 volumio[17351]: retrying in 5 seconds, trial 0 Feb 26 02:23:45 volumiopi5 volumio[17351]: info: Volumio Calling Home Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:45 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:46 volumiopi5 volumio[17351]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 26 02:23:46 volumiopi5 volumio[17351]: info: Discovery: Restarting Browsing Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:46 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:47 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:48 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:49 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:50 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:51 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:52 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:53 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:54 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:55 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:56 volumiopi5 volumio[17351]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 26 02:23:56 volumiopi5 volumio[17351]: info: Discovery: Restarting Browsing Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:56 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:57 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:58 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:59 volumiopi5 volumio[17351]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:23:59 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:00 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:01 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:02 volumiopi5 volumiossh-tunnel[17787]: ssh: Could not resolve hostname eu4.myvolumio.org: Device or resource busy Feb 26 02:24:02 volumiopi5 go-librespot[17766]: time="2026-02-26T02:24:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 26 02:24:02 volumiopi5 autossh[2369]: ssh exited with error status 255; restarting ssh Feb 26 02:24:02 volumiopi5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:24:02 volumiopi5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:02 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:03 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:04 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:05 volumiopi5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 26 02:24:05 volumiopi5 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:24:05 volumiopi5 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 26 02:24:05 volumiopi5 go-librespot[17835]: go-librespot daemon starting... Feb 26 02:24:05 volumiopi5 go-librespot[17836]: time="2026-02-26T02:24:05+01:00" level=info msg="running go-librespot 0.6.2" Feb 26 02:24:05 volumiopi5 go-librespot[17836]: time="2026-02-26T02:24:05+01:00" level=debug msg="app state loaded" Feb 26 02:24:05 volumiopi5 go-librespot[17836]: time="2026-02-26T02:24:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:05 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:06 volumiopi5 volumio[17351]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 26 02:24:06 volumiopi5 volumio[17351]: info: Discovery: Restarting Browsing Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:06 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:07 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:08 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:09 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:10 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:11 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:12 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:13 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:14 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:15 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:16 volumiopi5 volumio[17351]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 26 02:24:16 volumiopi5 volumio[17351]: info: Discovery: Restarting Browsing Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:16 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:17 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:18 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:18 volumiopi5 volumio[17351]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=2f3b82c1f6cb883d485bb1a63e524f7d" http://updates.volumio.org/downloader-v1/track-device Feb 26 02:24:18 volumiopi5 volumio[17351]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 26 02:24:18 volumiopi5 volumio[17351]: Dload Upload Total Spent Left Speed Feb 26 02:24:18 volumiopi5 volumio[17351]: [1.9K blob data] Feb 26 02:24:18 volumiopi5 volumio[17351]: retrying in 5 seconds, trial 1 Feb 26 02:24:18 volumiopi5 volumio[17351]: info: Volumio Calling Home Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:19 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:20 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:21 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:22 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:23 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:24 volumiopi5 go-librespot[17836]: time="2026-02-26T02:24:24+01:00" level=debug msg="new websocket client" Feb 26 02:24:24 volumiopi5 volumio[17351]: info: Connection to go-librespot Websocket established Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:24 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:25 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:26 volumiopi5 volumio[17351]: info: Discovery: Browse raised the following error Error: dns service error: unknown Feb 26 02:24:26 volumiopi5 volumio[17351]: info: Discovery: Restarting Browsing Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:26 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:27 volumiopi5 volumio[17351]: info: Getting Spotify volume Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:27 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:28 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:29 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:30 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:30 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:30 volumiopi5 volumio[17351]: info: CoreCommandRouter::volumioGetState Feb 26 02:24:30 volumiopi5 volumio[17351]: info: CorePlayQueue::getTrack 0 Feb 26 02:24:30 volumiopi5 go-librespot[17836]: time="2026-02-26T02:24:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 26 02:24:30 volumiopi5 volumio[17351]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 02:24:30 volumiopi5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 26 02:24:30 volumiopi5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 26 02:24:30 volumiopi5 volumio[17351]: Error: socket hang up Feb 26 02:24:30 volumiopi5 volumio[17351]: at connResetException (node:internal/errors:720:14) Feb 26 02:24:30 volumiopi5 volumio[17351]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 26 02:24:30 volumiopi5 volumio[17351]: at Socket.emit (node:events:526:35) Feb 26 02:24:30 volumiopi5 volumio[17351]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 26 02:24:30 volumiopi5 volumio[17351]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 26 02:24:30 volumiopi5 volumio[17351]: code: 'ECONNRESET', Feb 26 02:24:30 volumiopi5 volumio[17351]: response: undefined Feb 26 02:24:30 volumiopi5 volumio[17351]: } Feb 26 02:24:30 volumiopi5 volumio[17351]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 02:24:30 volumiopi5 sudo[17911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 02:23' Feb 26 02:24:30 volumiopi5 sudo[17911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"