Oct 16 18:02:53 antoozvolumio ntpd[1109]: CLOCK: time stepped by 38379.354718 Oct 16 18:02:53 antoozvolumio ntpd[1109]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 16 18:02:54 antoozvolumio volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 16 18:02:54 antoozvolumio volumio[1311]: info: VolumeController:: Volume=91 Mute =false Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreStateMachine::pushState Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioPushState Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreStateMachine::updateTrackBlock Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrackBlock Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 18:02:54 antoozvolumio volumio[1311]: info: Reloading queue from file Oct 16 18:02:54 antoozvolumio volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreStateMachine::setRepeat true single undefined Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreStateMachine::pushState Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioPushState Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreStateMachine::setRandom true Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreStateMachine::pushState Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 18:02:54 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioPushState Oct 16 18:02:54 antoozvolumio volumio[1311]: info: Setting Device type: Raspberry PI Oct 16 18:02:55 antoozvolumio volumio[1311]: info: Completed loading Core Plugins Oct 16 18:02:55 antoozvolumio volumio[1311]: info: Preparing to generate the ALSA configuration file Oct 16 18:02:55 antoozvolumio volumio[1311]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 16 18:02:55 antoozvolumio volumio[1311]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Oct 16 18:02:55 antoozvolumio volumio[1311]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 16 18:02:55 antoozvolumio volumio[1311]: info: The plugin glass has an ALSA contribution file Glass.postGlass.5.conf Oct 16 18:02:55 antoozvolumio volumio[1311]: info: Reading ALSA contributions from plugins. Oct 16 18:02:55 antoozvolumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 16 18:02:55 antoozvolumio sudo[1555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 16 18:02:55 antoozvolumio sudo[1555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:02:55 antoozvolumio volumio[1311]: info: VolumeController:: Volume=91 Mute =false Oct 16 18:02:55 antoozvolumio volumio[1311]: info: CoreStateMachine::pushState Oct 16 18:02:55 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:02:55 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioPushState Oct 16 18:02:55 antoozvolumio volumio[1311]: info: Discovery: A device disappeared from network Oct 16 18:02:55 antoozvolumio volumio-remote-updater[882]: [2026-10-16 18:02:55] [connect] Successful connection Oct 16 18:02:55 antoozvolumio volumio[1311]: info: Discovery: A device disappeared from network Oct 16 18:02:56 antoozvolumio volumio-remote-updater[882]: [2026-10-16 18:02:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792159375 101 Oct 16 18:02:56 antoozvolumio volumio[1311]: 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: 3 Oct 16 18:02:56 antoozvolumio volumio[1311]: info: Asound.conf file unchanged, so no further update is needed Oct 16 18:02:56 antoozvolumio volumio[1311]: info: Output device has changed, restarting MPD Oct 16 18:02:56 antoozvolumio volumio[1311]: info: Output device has changed, restarting Shairport Sync Oct 16 18:02:56 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:02:56 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 18:02:56 antoozvolumio sudo[1560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 18:02:56 antoozvolumio sudo[1560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:02:56 antoozvolumio sudo[1562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 18:02:56 antoozvolumio volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 18:02:56 antoozvolumio volumio[1311]: info: ___________ START PLUGINS ___________ Oct 16 18:02:56 antoozvolumio sudo[1560]: pam_unix(sudo:session): session closed for user root Oct 16 18:02:56 antoozvolumio sudo[1562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:02:56 antoozvolumio volumio[1311]: info: ControllerMpd::onStart: Initializing MPD Oct 16 18:02:56 antoozvolumio volumio[1311]: info: Creating MPD Configuration file Oct 16 18:02:56 antoozvolumio sudo[1569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 16 18:02:56 antoozvolumio sudo[1569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:02:56 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 18:02:56 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 18:02:56 antoozvolumio volumio[1311]: info: [1792159376688] CoreMusicLibrary::Adding element Media Servers Oct 16 18:02:56 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 18:02:56 antoozvolumio sudo[1573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 18:02:56 antoozvolumio sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:02:56 antoozvolumio volumio[1311]: info: UPNP Browser: Client initialized successfully Oct 16 18:02:56 antoozvolumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 16 18:02:56 antoozvolumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 16 18:02:56 antoozvolumio sudo[1576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 18:02:56 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:02:56 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 18:02:56 antoozvolumio sudo[1576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:02:56 antoozvolumio sudo[1573]: pam_unix(sudo:session): session closed for user root Oct 16 18:02:56 antoozvolumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 16 18:02:56 antoozvolumio sudo[1569]: pam_unix(sudo:session): session closed for user root Oct 16 18:02:56 antoozvolumio sudo[1579]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 18:02:56 antoozvolumio sudo[1579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 18:02:56 antoozvolumio sudo[1586]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 16 18:02:56 antoozvolumio sudo[1579]: pam_unix(sudo:session): session closed for user root Oct 16 18:02:56 antoozvolumio systemd[1]: mpd.service: Deactivated successfully. Oct 16 18:02:56 antoozvolumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 16 18:02:56 antoozvolumio systemd[1]: mpd.socket: Deactivated successfully. Oct 16 18:02:56 antoozvolumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 16 18:02:56 antoozvolumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 16 18:02:56 antoozvolumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 16 18:02:57 antoozvolumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 16 18:02:57 antoozvolumio volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 18:02:57 antoozvolumio sudo[1589]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 18:02:57 antoozvolumio sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 18:02:57 antoozvolumio sudo[1600]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 16 18:02:57 antoozvolumio sudo[1589]: pam_unix(sudo:session): session closed for user root Oct 16 18:02:57 antoozvolumio volumio[1311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 18:02:57 antoozvolumio volumio[1311]: info: [1792159377155] CoreMusicLibrary::Adding element Last_100 Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 18:02:57 antoozvolumio volumio[1311]: info: [1792159377213] CoreMusicLibrary::Adding element Webradio Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 18:02:57 antoozvolumio volumio5-onboarding[1584]: time=2026-10-16T18:02:57.307+04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 16 18:02:57 antoozvolumio volumio[1311]: info: Initializing BBC Radios Oct 16 18:02:57 antoozvolumio volumio[1503]: Starting albumart workers Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 18:02:57 antoozvolumio volumio[1311]: info: [1792159377601] CoreMusicLibrary::Adding element SoundCloud Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 18:02:57 antoozvolumio volumio[1311]: Cannot find translation for source SoundCloud Oct 16 18:02:57 antoozvolumio volumio[1311]: info: Creating Spotify config file Oct 16 18:02:57 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:02:58 antoozvolumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 16 18:02:58 antoozvolumio volumio[1501]: Starting albumart workers Oct 16 18:02:58 antoozvolumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 16 18:02:58 antoozvolumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 18:02:58 antoozvolumio volumio[1502]: Starting albumart workers Oct 16 18:02:58 antoozvolumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 16 18:02:58 antoozvolumio sudo[1555]: pam_unix(sudo:session): session closed for user root Oct 16 18:02:58 antoozvolumio dhcpcd[797]: eth0: carrier lost Oct 16 18:02:58 antoozvolumio dhcpcd[890]: eth0: carrier lost Oct 16 18:02:58 antoozvolumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Oct 16 18:02:58 antoozvolumio avahi-daemon[849]: Withdrawing address record for 10.230.230.111 on eth0. Oct 16 18:02:58 antoozvolumio avahi-daemon[849]: Leaving mDNS multicast group on interface eth0.IPv4 with address 10.230.230.111. Oct 16 18:02:58 antoozvolumio avahi-daemon[849]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 16 18:02:58 antoozvolumio dhcpcd[797]: eth0: deleting route to 10.230.230.0/24 Oct 16 18:02:58 antoozvolumio dhcpcd[797]: eth0: deleting default route via 10.230.230.148 Oct 16 18:02:59 antoozvolumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 16 18:02:59 antoozvolumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 16 18:02:59 antoozvolumio systemd[1]: welcome.service: Deactivated successfully. Oct 16 18:02:59 antoozvolumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 16 18:02:59 antoozvolumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 16 18:02:59 antoozvolumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 16 18:02:59 antoozvolumio welcome[1673]: Resolved ip:[0] Oct 16 18:02:59 antoozvolumio nmbd[1195]: [2026/10/16 18:02:59.072777, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Oct 16 18:02:59 antoozvolumio nmbd[1195]: Packet send failed to 10.230.230.255(137) ERRNO=Network is unreachable Oct 16 18:02:59 antoozvolumio nmbd[1195]: [2026/10/16 18:02:59.072964, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Oct 16 18:02:59 antoozvolumio nmbd[1195]: retransmit_or_expire_response_records: Failed to resend packet id 28332 to IP 10.230.230.255 on subnet 10.230.230.111 Oct 16 18:02:59 antoozvolumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 16 18:02:59 antoozvolumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 16 18:02:59 antoozvolumio ifplugd(eth0)[1122]: Link beat lost. Oct 16 18:02:59 antoozvolumio volumio[1311]: info: AutoStart - onStart - waiting for system ready state Oct 16 18:02:59 antoozvolumio volumio[1311]: info: AutoStart - Polling config: interval=5000ms, maxAttempts=60 Oct 16 18:02:59 antoozvolumio volumio[1311]: info: AutoStart - Maximum wait time: 300 seconds Oct 16 18:02:59 antoozvolumio volumio[1311]: info: AutoStart - Startup volume disabled Oct 16 18:02:59 antoozvolumio volumio[1311]: info: AutoStart - Check #1/60 - VOLUMIO_SYSTEM_STATUS = starting Oct 16 18:02:59 antoozvolumio volumio[1311]: info: [now-playing] ConfigUpdater: config is up to date. Oct 16 18:02:59 antoozvolumio volumio[1311]: info: Loading i18n strings for locale en Oct 16 18:03:00 antoozvolumio nmbd[1195]: [2026/10/16 18:03:00.073625, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Oct 16 18:03:00 antoozvolumio nmbd[1195]: Packet send failed to 10.230.230.255(137) ERRNO=Network is unreachable Oct 16 18:03:00 antoozvolumio nmbd[1195]: [2026/10/16 18:03:00.073742, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Oct 16 18:03:00 antoozvolumio nmbd[1195]: retransmit_or_expire_response_records: Failed to resend packet id 28332 to IP 10.230.230.255 on subnet 10.230.230.111 Oct 16 18:03:00 antoozvolumio ntpd[1109]: IO: Deleting interface #3 eth0, 10.230.230.111#123, interface stats: received=19, sent=19, dropped=0, active_time=18 secs Oct 16 18:03:00 antoozvolumio ntpd[1109]: PROTO: 47.91.111.180 unlink local addr 10.230.230.111 -> Oct 16 18:03:00 antoozvolumio ntpd[1109]: PROTO: 78.40.197.59 unlink local addr 10.230.230.111 -> Oct 16 18:03:00 antoozvolumio ntpd[1109]: PROTO: 89.36.162.11 unlink local addr 10.230.230.111 -> Oct 16 18:03:00 antoozvolumio ntpd[1109]: PROTO: 31.56.211.153 unlink local addr 10.230.230.111 -> Oct 16 18:03:00 antoozvolumio ntpd[1109]: PROTO: 5.32.10.107 unlink local addr 10.230.230.111 -> Oct 16 18:03:00 antoozvolumio dhcpcd[890]: eth0: carrier acquired Oct 16 18:03:00 antoozvolumio dhcpcd[797]: eth0: carrier acquired Oct 16 18:03:00 antoozvolumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Oct 16 18:03:00 antoozvolumio dhcpcd[797]: eth0: IAID 67:41:8f:3d Oct 16 18:03:00 antoozvolumio dhcpcd[890]: eth0: IAID 67:41:8f:3d Oct 16 18:03:01 antoozvolumio nmbd[1195]: [2026/10/16 18:03:01.077719, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Oct 16 18:03:01 antoozvolumio nmbd[1195]: Packet send failed to 10.230.230.255(137) ERRNO=Network is unreachable Oct 16 18:03:01 antoozvolumio nmbd[1195]: [2026/10/16 18:03:01.077829, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Oct 16 18:03:01 antoozvolumio nmbd[1195]: retransmit_or_expire_response_records: Failed to resend packet id 28332 to IP 10.230.230.255 on subnet 10.230.230.111 Oct 16 18:03:01 antoozvolumio ifplugd(eth0)[1122]: Link beat detected. Oct 16 18:03:01 antoozvolumio mpd[1601]: 2026-10-16T18:03:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 18:03:01 antoozvolumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 16 18:03:01 antoozvolumio sudo[1576]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:01 antoozvolumio sudo[1562]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:01 antoozvolumio dhcpcd[890]: eth0: soliciting a DHCP lease Oct 16 18:03:01 antoozvolumio dhcpcd[890]: eth0: soliciting an IPv6 router Oct 16 18:03:01 antoozvolumio dhcpcd[797]: eth0: soliciting an IPv6 router Oct 16 18:03:01 antoozvolumio volumio[1311]: info: Adding glass_artistfanart REST Endpoint for plugin: user_interface/glass Oct 16 18:03:01 antoozvolumio volumio[1311]: info: Adding glass_screen REST Endpoint for plugin: user_interface/glass Oct 16 18:03:02 antoozvolumio volumio[1311]: info: Loading i18n strings for locale en Oct 16 18:03:02 antoozvolumio nmbd[1195]: [2026/10/16 18:03:02.079032, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Oct 16 18:03:02 antoozvolumio nmbd[1195]: Packet send failed to 10.230.230.255(138) ERRNO=Network is unreachable Oct 16 18:03:02 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 18:03:02 antoozvolumio volumio[1311]: info: Volumio Calling Home Oct 16 18:03:02 antoozvolumio sudo[1746]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Oct 16 18:03:02 antoozvolumio sudo[1746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:02 antoozvolumio sudo[1746]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:02 antoozvolumio dhcpcd[797]: eth0: soliciting a DHCP lease Oct 16 18:03:02 antoozvolumio sudo[1753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 16 18:03:02 antoozvolumio sudo[1753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:02 antoozvolumio sudo[1755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 16 18:03:02 antoozvolumio sudo[1755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:02 antoozvolumio sudo[1757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 16 18:03:02 antoozvolumio sudo[1757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:02 antoozvolumio sudo[1753]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:02 antoozvolumio systemd[1]: Reloading. Oct 16 18:03:02 antoozvolumio volumio[1311]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): OpenWeatherMap API key not configured. Add it in Weather Service settings. Oct 16 18:03:02 antoozvolumio volumio[1311]: info: [now-playing] App is listening on port 4004. Oct 16 18:03:03 antoozvolumio volumio[1311]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Oct 16 18:03:03 antoozvolumio volumio[1311]: info: Discovery: adding 34492128-db98-414d-8daf-c13be836fca8 Oct 16 18:03:03 antoozvolumio volumio[1311]: info: Discovery: Found device AntoozVolumio Oct 16 18:03:03 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 16 18:03:03 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:03:03 antoozvolumio volumio[1311]: info: Discovery: this is already registered, 34492128-db98-414d-8daf-c13be836fca8 Oct 16 18:03:03 antoozvolumio volumio[1311]: info: Discovery: Found device AntoozVolumio Oct 16 18:03:03 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 16 18:03:03 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:03:03 antoozvolumio volumio[1311]: info: Discovery: this is already registered, 34492128-db98-414d-8daf-c13be836fca8 Oct 16 18:03:03 antoozvolumio volumio[1311]: info: Discovery: Found device AntoozVolumio Oct 16 18:03:03 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 16 18:03:03 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:03:03 antoozvolumio volumio[1311]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Oct 16 18:03:03 antoozvolumio volumio[1311]: info: Discovery: this is already registered, 34492128-db98-414d-8daf-c13be836fca8 Oct 16 18:03:03 antoozvolumio volumio[1311]: info: Discovery: Found device AntoozVolumio Oct 16 18:03:03 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 16 18:03:03 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:03:03 antoozvolumio volumio[1311]: info: touch_display: Backlight interface detected. Oct 16 18:03:03 antoozvolumio volumio[1311]: info: Volumio Network Manager: Network status updated: 0 Oct 16 18:03:03 antoozvolumio volumio[1311]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 16 18:03:03 antoozvolumio systemd[1]: Reloading. Oct 16 18:03:03 antoozvolumio sudo[1757]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:03 antoozvolumio volumio[1311]: info: MPD Permissions set Oct 16 18:03:03 antoozvolumio volumio[1311]: info: MPD Permissions set Oct 16 18:03:03 antoozvolumio volumio[1311]: info: Upmpdcli Daemon Started Oct 16 18:03:04 antoozvolumio volumio[1311]: 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 Oct 16 18:03:04 antoozvolumio volumio[1311]: info: MPD running with PID1601 Oct 16 18:03:04 antoozvolumio volumio[1311]: ,establishing connection Oct 16 18:03:04 antoozvolumio volumio[1311]: info: touch_display: systemctl daemon-reload succeeded. Oct 16 18:03:04 antoozvolumio sudo[1782]: 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/ Oct 16 18:03:04 antoozvolumio sudo[1782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:04 antoozvolumio volumio[1311]: info: Spotify config file written Oct 16 18:03:04 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 16 18:03:04 antoozvolumio volumio[1311]: info: Preparing to generate the ALSA configuration file Oct 16 18:03:04 antoozvolumio sudo[1807]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 16 18:03:04 antoozvolumio sudo[1807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:04 antoozvolumio sudo[1810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 16 18:03:04 antoozvolumio sudo[1810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:04 antoozvolumio sudo[1782]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:04 antoozvolumio volumio[1311]: 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 Oct 16 18:03:04 antoozvolumio volumio[1311]: info: Received Get System Info Oct 16 18:03:04 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:03:04 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:03:04 antoozvolumio volumio[1311]: info: Discovery: Getting this device information Oct 16 18:03:04 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 16 18:03:04 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:03:04 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:03:04 antoozvolumio volumio5-onboarding[1584]: time=2026-10-16T18:03:04.601+04:00 level=INFO msg="system info for 0fa872214ab9fdf2292dfe9de46799d6" deviceName=AntoozVolumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Oct 16 18:03:04 antoozvolumio volumio5-onboarding[1584]: time=2026-10-16T18:03:04.604+04:00 level=INFO msg="bootstrapping state" hasInternet=false Oct 16 18:03:04 antoozvolumio sudo[1755]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:04 antoozvolumio 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. Oct 16 18:03:04 antoozvolumio 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. Oct 16 18:03:04 antoozvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 18:03:04 antoozvolumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Oct 16 18:03:04 antoozvolumio sudo[1810]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:04 antoozvolumio go-librespot[1813]: go-librespot daemon starting... Oct 16 18:03:04 antoozvolumio sudo[1807]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:04 antoozvolumio volumio[1311]: error: MPD error: The expression evaluated to a falsy value: Oct 16 18:03:04 antoozvolumio volumio[1311]: assert.ok(self.idling) Oct 16 18:03:04 antoozvolumio volumio[1311]: error: The expression evaluated to a falsy value: Oct 16 18:03:04 antoozvolumio volumio[1311]: assert.ok(self.idling) Oct 16 18:03:04 antoozvolumio volumio[1311]: error: MPD error: The expression evaluated to a falsy value: Oct 16 18:03:04 antoozvolumio volumio[1311]: assert.ok(self.idling) Oct 16 18:03:04 antoozvolumio volumio[1311]: error: The expression evaluated to a falsy value: Oct 16 18:03:04 antoozvolumio volumio[1311]: assert.ok(self.idling) Oct 16 18:03:05 antoozvolumio volumio[1311]: info: AutoStart - Check #2/60 - VOLUMIO_SYSTEM_STATUS = starting Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio startx[1842]: X.Org X Server 1.21.1.7 Oct 16 18:03:05 antoozvolumio startx[1842]: X Protocol Version 11, Revision 0 Oct 16 18:03:05 antoozvolumio startx[1842]: Current Operating System: Linux antoozvolumio 6.12.74-v7l+ #1948 SMP Mon Mar 2 11:27:49 GMT 2026 armv7l Oct 16 18:03:05 antoozvolumio startx[1842]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_hdmi=0 snd_bcm2835.enable_hdmi=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=0 numa=fake=2 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=2C:CF:67:41:8F:3D vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=bea3e05c-62cf-4b22-a58d-66213927f56a uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no video=DSI-1:400x1280e,rotate=90 video=HDMI-A-1:d video=HDMI-A-2:d Oct 16 18:03:05 antoozvolumio startx[1842]: xorg-server 2:21.1.7-3+rpt3+deb12u13 (https://www.debian.org/support) Oct 16 18:03:05 antoozvolumio startx[1842]: Current version of pixman: 0.44.0 Oct 16 18:03:05 antoozvolumio startx[1842]: Before reporting problems, check http://wiki.x.org Oct 16 18:03:05 antoozvolumio startx[1842]: to make sure that you have the latest version. Oct 16 18:03:05 antoozvolumio startx[1842]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 16 18:03:05 antoozvolumio startx[1842]: (++) from command line, (!!) notice, (II) informational, Oct 16 18:03:05 antoozvolumio startx[1842]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 16 18:03:05 antoozvolumio startx[1842]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Oct 16 18:03:05 2026 Oct 16 18:03:05 antoozvolumio startx[1842]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 16 18:03:05 antoozvolumio startx[1842]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 18:03:05 antoozvolumio volumio[1311]: info: No need to fix Spotify hosts Oct 16 18:03:05 antoozvolumio volumio[1311]: info: Received Get System Info Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:03:05 antoozvolumio volumio[1311]: info: Discovery: Getting this device information Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:03:05 antoozvolumio volumio[1311]: info: The plugin glass has an ALSA contribution file Glass.postGlass.5.conf Oct 16 18:03:05 antoozvolumio volumio[1311]: info: Reading ALSA contributions from plugins. Oct 16 18:03:05 antoozvolumio go-librespot[1815]: time="2026-10-16T18:03:05+04:00" level=info msg="running go-librespot 0.9.1" Oct 16 18:03:05 antoozvolumio go-librespot[1815]: time="2026-10-16T18:03:05+04:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 18:03:05 antoozvolumio go-librespot[1815]: time="2026-10-16T18:03:05+04:00" level=debug msg="app state loaded" Oct 16 18:03:05 antoozvolumio go-librespot[1815]: time="2026-10-16T18:03:05+04:00" level=fatal msg="daemon exited with error" 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" Oct 16 18:03:05 antoozvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 18:03:05 antoozvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 18:03:05 antoozvolumio volumio[1311]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 16 18:03:05 antoozvolumio volumio[1311]: info: touch_display: Volumio Kiosk started. Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::volumioGetState Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CorePlayQueue::getTrack 0 Oct 16 18:03:05 antoozvolumio volumio[1311]: info: Completed starting Core Plugins Oct 16 18:03:05 antoozvolumio volumio[1311]: info: ------------------------------------------- Oct 16 18:03:05 antoozvolumio volumio[1311]: info: ----- MyVolumio plugins startup ---- Oct 16 18:03:05 antoozvolumio volumio[1311]: info: ------------------------------------------- Oct 16 18:03:05 antoozvolumio volumio[1311]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 16 18:03:05 antoozvolumio volumio[1311]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 16 18:03:05 antoozvolumio volumio[1311]: info: touch_display: IframeKeyboardBridge extension installed successfully Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 18:03:05 antoozvolumio volumio[1311]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 18:03:05 antoozvolumio volumio5-onboarding[1584]: time=2026-10-16T18:03:05.579+04:00 level=INFO msg="enabling local network discovery" Oct 16 18:03:05 antoozvolumio volumio[1311]: error: updateQueue error: null Oct 16 18:03:05 antoozvolumio volumio[1311]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/C0BD-0F2B/Malayalam Christian Bakthi Ganangal/K. J. Yesudas - Appavum Veenjumayi Nin.flac","favourite":false} Oct 16 18:03:05 antoozvolumio volumio5-onboarding[1584]: time=2026-10-16T18:03:05.600+04:00 level=INFO msg="enabling BLE discovery" Oct 16 18:03:05 antoozvolumio volumio[1311]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 16 18:03:05 antoozvolumio sudo[1864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Oct 16 18:03:05 antoozvolumio sudo[1864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:05 antoozvolumio sudo[1864]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:05 antoozvolumio sudo[1867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 16 18:03:05 antoozvolumio sudo[1867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:05 antoozvolumio volumio[1311]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 16 18:03:05 antoozvolumio sudo[1867]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:05 antoozvolumio sudo[1870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 16 18:03:05 antoozvolumio sudo[1870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:05 antoozvolumio sudo[1870]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:06 antoozvolumio volumio[1311]: info: Starting Shairport Sync Oct 16 18:03:06 antoozvolumio volumio[1311]: info: Starting Shairport Sync Oct 16 18:03:06 antoozvolumio volumio[1311]: info: Starting Shairport Sync Oct 16 18:03:06 antoozvolumio dhcpcd[890]: eth0: probing address 10.230.230.111/24 Oct 16 18:03:06 antoozvolumio dhcpcd[797]: eth0: probing address 10.230.230.111/24 Oct 16 18:03:06 antoozvolumio volumio[1311]: info: [now-playing] MyBackgroundMonitor captured 'add': BMW M3 Black Headlights.jpg Oct 16 18:03:06 antoozvolumio volumio[1311]: info: [now-playing] MyBackgroundMonitor captured 'add': Camaro.jpg Oct 16 18:03:06 antoozvolumio sudo[1875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 18:03:06 antoozvolumio sudo[1875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:06 antoozvolumio volumio[1311]: info: [now-playing] MyBackgroundMonitor captured 'add': Mustang.jpg Oct 16 18:03:06 antoozvolumio volumio[1311]: info: [now-playing] MyBackgroundMonitor captured 'add': Porche.jpg Oct 16 18:03:06 antoozvolumio sudo[1880]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 18:03:06 antoozvolumio sudo[1880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:06 antoozvolumio volumio[1311]: info: [now-playing] MyBackgroundMonitor captured 'add': Scania.jpg Oct 16 18:03:06 antoozvolumio volumio[1311]: info: [now-playing] MyBackgroundMonitor captured 'add': X6.jpg Oct 16 18:03:06 antoozvolumio sudo[1879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 18:03:06 antoozvolumio sudo[1879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:06 antoozvolumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 16 18:03:06 antoozvolumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 16 18:03:06 antoozvolumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 18:03:06 antoozvolumio systemd[1]: shairport-sync.service: Consumed 1.567s CPU time. Oct 16 18:03:06 antoozvolumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 18:03:06 antoozvolumio volumio[1311]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Oct 16 18:03:06 antoozvolumio volumio[1311]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 16 18:03:06 antoozvolumio volumio[1311]: info: touch_display: File permissions for backlight brightness control set. Oct 16 18:03:06 antoozvolumio sudo[1880]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:06 antoozvolumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 16 18:03:06 antoozvolumio volumio[1311]: info: touch_display: X display number found: 0 Oct 16 18:03:06 antoozvolumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 16 18:03:06 antoozvolumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 18:03:06 antoozvolumio volumio5-onboarding[1584]: time=2026-10-16T18:03:06.646+04:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 18:03:06 antoozvolumio volumio[1311]: info: Shairport-Sync Started Oct 16 18:03:06 antoozvolumio volumio[1311]: Error adding Membership: Error: addMembership EINVAL Oct 16 18:03:06 antoozvolumio volumio[1311]: info: touch_display: X display number found: 0 Oct 16 18:03:06 antoozvolumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 18:03:06 antoozvolumio sudo[1875]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:06 antoozvolumio sudo[1879]: pam_unix(sudo:session): session closed for user root Oct 16 18:03:06 antoozvolumio volumio[1311]: info: Discovery: A device disappeared from network Oct 16 18:03:06 antoozvolumio volumio[1311]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 16 18:03:06 antoozvolumio volumio[1311]: info: Shairport-Sync Started Oct 16 18:03:06 antoozvolumio volumio[1311]: info: touch_display: X display number found: 0 Oct 16 18:03:06 antoozvolumio volumio[1311]: info: Shairport-Sync Started Oct 16 18:03:07 antoozvolumio volumio[1311]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 18:03:07 antoozvolumio volumio[1311]: Error: addMembership ENODEV Oct 16 18:03:07 antoozvolumio volumio[1311]: at Socket.addMembership (node:dgram:860:11) Oct 16 18:03:07 antoozvolumio volumio[1311]: at addMembership (/volumio/node_modules/node-ssdp/lib/index.js:264:16) Oct 16 18:03:07 antoozvolumio volumio[1311]: at runNextTicks (node:internal/process/task_queues:60:5) Oct 16 18:03:07 antoozvolumio volumio[1311]: at process.processTimers (node:internal/timers:511:9) { Oct 16 18:03:07 antoozvolumio volumio[1311]: errno: -19, Oct 16 18:03:07 antoozvolumio volumio[1311]: code: 'ENODEV', Oct 16 18:03:07 antoozvolumio volumio[1311]: syscall: 'addMembership' Oct 16 18:03:07 antoozvolumio volumio[1311]: } Oct 16 18:03:07 antoozvolumio volumio[1311]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 18:03:08 antoozvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Oct 16 18:03:08 antoozvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 18:03:08 antoozvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 18:03:08 antoozvolumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 16 18:03:08 antoozvolumio go-librespot[2015]: go-librespot daemon starting... Oct 16 18:03:08 antoozvolumio go-librespot[2017]: time="2026-10-16T18:03:08+04:00" level=info msg="running go-librespot 0.9.1" Oct 16 18:03:09 antoozvolumio go-librespot[2017]: time="2026-10-16T18:03:09+04:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 18:03:09 antoozvolumio go-librespot[2017]: time="2026-10-16T18:03:09+04:00" level=debug msg="app state loaded" Oct 16 18:03:09 antoozvolumio go-librespot[2017]: time="2026-10-16T18:03:09+04:00" level=fatal msg="daemon exited with error" 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" Oct 16 18:03:09 antoozvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 16 18:03:09 antoozvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 16 18:03:10 antoozvolumio dhcpcd[890]: eth0: leased 10.230.230.111 for 3599 seconds Oct 16 18:03:10 antoozvolumio avahi-daemon[849]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.230.230.111. Oct 16 18:03:10 antoozvolumio avahi-daemon[849]: New relevant interface eth0.IPv4 for mDNS. Oct 16 18:03:10 antoozvolumio avahi-daemon[849]: Registering new address record for 10.230.230.111 on eth0.IPv4. Oct 16 18:03:10 antoozvolumio dhcpcd[890]: eth0: adding route to 10.230.230.0/24 Oct 16 18:03:10 antoozvolumio dhcpcd[890]: eth0: adding default route via 10.230.230.148 Oct 16 18:03:10 antoozvolumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 16 18:03:10 antoozvolumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 16 18:03:10 antoozvolumio systemd[1]: welcome.service: Deactivated successfully. Oct 16 18:03:10 antoozvolumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 16 18:03:10 antoozvolumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 16 18:03:10 antoozvolumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 16 18:03:11 antoozvolumio welcome[2097]: Resolved ip:[1] 10.230.230.111 Oct 16 18:03:11 antoozvolumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 16 18:03:11 antoozvolumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 16 18:03:11 antoozvolumio dhcpcd[797]: eth0: leased 10.230.230.111 for 3599 seconds Oct 16 18:03:11 antoozvolumio dhcpcd[797]: eth0: adding route to 10.230.230.0/24 Oct 16 18:03:11 antoozvolumio dhcpcd[797]: eth0: adding default route via 10.230.230.148 Oct 16 18:03:11 antoozvolumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 16 18:03:11 antoozvolumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 16 18:03:11 antoozvolumio systemd[1]: welcome.service: Deactivated successfully. Oct 16 18:03:11 antoozvolumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 16 18:03:11 antoozvolumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 16 18:03:11 antoozvolumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 16 18:03:11 antoozvolumio welcome[2148]: Resolved ip:[1] 10.230.230.111 Oct 16 18:03:11 antoozvolumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 16 18:03:11 antoozvolumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 16 18:03:12 antoozvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Oct 16 18:03:12 antoozvolumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 16 18:03:12 antoozvolumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 16 18:03:12 antoozvolumio go-librespot[2175]: go-librespot daemon starting... Oct 16 18:03:12 antoozvolumio go-librespot[2176]: time="2026-10-16T18:03:12+04:00" level=info msg="running go-librespot 0.9.1" Oct 16 18:03:12 antoozvolumio go-librespot[2176]: time="2026-10-16T18:03:12+04:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 16 18:03:12 antoozvolumio go-librespot[2176]: time="2026-10-16T18:03:12+04:00" level=debug msg="app state loaded" Oct 16 18:03:12 antoozvolumio ntpd[1109]: IO: Listen normally on 4 eth0 10.230.230.111:123 Oct 16 18:03:12 antoozvolumio ntpd[1109]: IO: new interface(s) found: waking up resolver Oct 16 18:03:12 antoozvolumio ntpd[1109]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 16 18:03:12 antoozvolumio ntpd[1109]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 16 18:03:12 antoozvolumio ntpd[1109]: DNS: Pool skipping: 5.32.10.107 Oct 16 18:03:12 antoozvolumio ntpd[1109]: DNS: Pool skipping: 31.56.211.153 Oct 16 18:03:12 antoozvolumio ntpd[1109]: DNS: Pool skipping: 78.40.197.59 Oct 16 18:03:12 antoozvolumio ntpd[1109]: DNS: Pool skipping: 89.36.162.11 Oct 16 18:03:12 antoozvolumio ntpd[1109]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 16 18:03:13 antoozvolumio sudo[2203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 18:02' Oct 16 18:03:13 antoozvolumio sudo[2203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 18:03:13 antoozvolumio go-librespot[2176]: time="2026-10-16T18:03:13+04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 16 18:03:13 antoozvolumio go-librespot[2176]: time="2026-10-16T18:03:13+04:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 16 18:03:13 antoozvolumio go-librespot[2176]: time="2026-10-16T18:03:13+04:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 16 18:03:13 antoozvolumio go-librespot[2176]: time="2026-10-16T18:03:13+04:00" level=info msg="zeroconf server listening on port 39693" Oct 16 18:03:13 antoozvolumio go-librespot[2176]: time="2026-10-16T18:03:13+04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 16 18:03:13 antoozvolumio go-librespot[2176]: time="2026-10-16T18:03:13+04:00" level=info msg="loading previously persisted zeroconf credentials" username="31************************gy" 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"