-- Logs begin at Thu 2019-02-14 18:11:58 WITA, end at Tue 2026-03-31 14:10:47 WITA. -- Mar 31 14:09:02 volumio3118d go-librespot[1739]: time="2026-03-31T14:09:02+08:00" level=debug msg="new websocket client" Mar 31 14:09:02 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 31 14:09:02 volumio3118d volumio[1017]: info: Connection to go-librespot Websocket established Mar 31 14:09:02 volumio3118d go-librespot[1739]: time="2026-03-31T14:09:02+08: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" Mar 31 14:09:02 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:09:02 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:09:02 volumio3118d volumio[1017]: info: Connection to go-librespot Websocket closed Mar 31 14:09:05 volumio3118d volumio[1017]: info: Getting Spotify volume Mar 31 14:09:05 volumio3118d volumio[1017]: (node:1017) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 31 14:09:05 volumio3118d volumio[1017]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 31 14:09:05 volumio3118d volumio[1017]: (node:1017) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) Mar 31 14:09:05 volumio3118d volumio[1017]: info: Initializing connection to go-librespot Websocket Mar 31 14:09:05 volumio3118d systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 31 14:09:05 volumio3118d systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Mar 31 14:09:05 volumio3118d systemd[1]: Stopped go-librespot Daemon. Mar 31 14:09:05 volumio3118d systemd[1]: Started go-librespot Daemon. Mar 31 14:09:05 volumio3118d go-librespot[1798]: go-librespot daemon starting... Mar 31 14:09:05 volumio3118d go-librespot[1798]: time="2026-03-31T14:09:05+08:00" level=info msg="running go-librespot 0.4.0" Mar 31 14:09:05 volumio3118d go-librespot[1798]: time="2026-03-31T14:09:05+08:00" level=debug msg="app state loaded" Mar 31 14:09:05 volumio3118d go-librespot[1798]: time="2026-03-31T14:09:05+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 31 14:09:08 volumio3118d ntpd[780]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 31 14:09:11 volumio3118d volumio-time-update[601]: volumio-time-update-util: Date not found in response Mar 31 14:09:11 volumio3118d volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Mar 31 14:09:16 volumio3118d volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Mar 31 14:09:25 volumio3118d go-librespot[1798]: time="2026-03-31T14:09:25+08: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" Mar 31 14:09:25 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:09:25 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:09:28 volumio3118d ntpd[780]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 31 14:09:28 volumio3118d systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 31 14:09:28 volumio3118d systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Mar 31 14:09:28 volumio3118d systemd[1]: Stopped go-librespot Daemon. Mar 31 14:09:28 volumio3118d systemd[1]: Started go-librespot Daemon. Mar 31 14:09:28 volumio3118d go-librespot[1845]: go-librespot daemon starting... Mar 31 14:09:28 volumio3118d go-librespot[1845]: time="2026-03-31T14:09:28+08:00" level=info msg="running go-librespot 0.4.0" Mar 31 14:09:28 volumio3118d go-librespot[1845]: time="2026-03-31T14:09:28+08:00" level=debug msg="app state loaded" Mar 31 14:09:28 volumio3118d go-librespot[1845]: time="2026-03-31T14:09:28+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 31 14:09:29 volumio3118d hostapd[1011]: wlan0: STA 7a:96:b3:ab:59:77 IEEE 802.11: associated Mar 31 14:09:29 volumio3118d hostapd[1011]: wlan0: STA 7a:96:b3:ab:59:77 RADIUS: starting accounting session CF24F023E14FC7E7 Mar 31 14:09:29 volumio3118d dnsmasq-dhcp[1007]: DHCPDISCOVER(wlan0) 7a:96:b3:ab:59:77 Mar 31 14:09:29 volumio3118d dnsmasq-dhcp[1007]: DHCPOFFER(wlan0) 192.168.211.146 7a:96:b3:ab:59:77 Mar 31 14:09:29 volumio3118d dnsmasq-dhcp[1007]: DHCPREQUEST(wlan0) 192.168.211.146 7a:96:b3:ab:59:77 Mar 31 14:09:29 volumio3118d dnsmasq-dhcp[1007]: DHCPACK(wlan0) 192.168.211.146 7a:96:b3:ab:59:77 Redmi-Note-13-Pro-5G Mar 31 14:09:33 volumio3118d sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 14:09:33 volumio3118d sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:09:33 volumio3118d sudo[1854]: pam_unix(sudo:session): session closed for user root Mar 31 14:09:33 volumio3118d sudo[1856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:09:33 volumio3118d sudo[1856]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:09:33 volumio3118d sudo[1856]: pam_unix(sudo:session): session closed for user root Mar 31 14:09:33 volumio3118d volumio[1017]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.146 UA: Mozilla/5.0 (Linux; Android 16; 2312DRA50G Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.57 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 31 14:09:34 volumio3118d sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 14:09:34 volumio3118d sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:09:34 volumio3118d sudo[1860]: pam_unix(sudo:session): session closed for user root Mar 31 14:09:34 volumio3118d sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:09:34 volumio3118d sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:09:34 volumio3118d sudo[1863]: pam_unix(sudo:session): session closed for user root Mar 31 14:09:34 volumio3118d volumio[1017]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.146 UA: Mozilla/5.0 (Linux; Android 16; 2312DRA50G Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.57 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetVisibleSources Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:09:34 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 31 14:09:34 volumio3118d volumio[1017]: info: Received Get System Info Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 14:09:34 volumio3118d volumio[1017]: info: Discovery: Getting this device information Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:09:34 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:09:34 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:34 volumio3118d volumio[1017]: info: Listing playlists Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 31 14:09:34 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 31 14:09:35 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 31 14:09:36 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 31 14:09:36 volumio3118d volumio[1017]: info: Received Get System Info Mar 31 14:09:36 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 14:09:36 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 14:09:36 volumio3118d volumio[1017]: info: Discovery: Getting this device information Mar 31 14:09:36 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:09:36 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:36 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 14:09:36 volumio3118d volumio-time-update[601]: volumio-time-update-util: Date not found in response Mar 31 14:09:36 volumio3118d volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioPlay Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::play index undefined Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::startPlaybackTimer Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::clearAddPlayTracks NAS/nekosynology/Music 1/New folder/INXS - The Swing (1984) [2014 _AcousticSounds 24-192]/01-Original Sin.flac Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand stop Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand stop took 4 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand clear Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand clear took 0 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand add "NAS/nekosynology/Music 1/New folder/INXS - The Swing (1984) [2014 _AcousticSounds 24-192]/01-Original Sin.flac" Mar 31 14:09:39 volumio3118d volumio[1017]: info: Mar 31 14:09:39 volumio3118d volumio[1017]: ---------------------------- MPD announces system playlist update Mar 31 14:09:39 volumio3118d volumio[1017]: info: Ignoring MPD Status Update Mar 31 14:09:39 volumio3118d volumio[1017]: info: Mar 31 14:09:39 volumio3118d volumio[1017]: ---------------------------- MPD announces system playlist update Mar 31 14:09:39 volumio3118d volumio[1017]: info: Ignoring MPD Status Update Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand add "NAS/nekosynology/Music 1/New folder/INXS - The Swing (1984) [2014 _AcousticSounds 24-192]/01-Original Sin.flac" took 4 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand play Mar 31 14:09:39 volumio3118d volumio[1017]: info: Mar 31 14:09:39 volumio3118d volumio[1017]: ---------------------------- MPD announces system playlist update Mar 31 14:09:39 volumio3118d volumio[1017]: info: Ignoring MPD Status Update Mar 31 14:09:39 volumio3118d volumio[1017]: info: Mar 31 14:09:39 volumio3118d volumio[1017]: ---------------------------- MPD announces system playlist update Mar 31 14:09:39 volumio3118d volumio[1017]: info: Ignoring MPD Status Update Mar 31 14:09:39 volumio3118d volumio[1017]: error: Upnp client error: Error: This socket has been ended by the other party Mar 31 14:09:39 volumio3118d volumio[1017]: info: Mar 31 14:09:39 volumio3118d volumio[1017]: ---------------------------- MPD announces state update: player Mar 31 14:09:39 volumio3118d volumio[1017]: info: ------------------------------ 11ms Mar 31 14:09:39 volumio3118d volumio[1017]: info: ------------------------------ 10ms Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand play took 8 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: info: ControllerMpd::getState Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand status Mar 31 14:09:39 volumio3118d volumio[1017]: info: ------------------------------ 7ms Mar 31 14:09:39 volumio3118d volumio[1017]: info: ------------------------------ 6ms Mar 31 14:09:39 volumio3118d volumio[1017]: info: Mar 31 14:09:39 volumio3118d volumio[1017]: ---------------------------- MPD announces system playlist update Mar 31 14:09:39 volumio3118d volumio[1017]: info: Ignoring MPD Status Update Mar 31 14:09:39 volumio3118d volumio[1017]: info: Mar 31 14:09:39 volumio3118d volumio[1017]: ---------------------------- MPD announces state update: player Mar 31 14:09:39 volumio3118d volumio[1017]: info: ControllerMpd::getState Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand status Mar 31 14:09:39 volumio3118d volumio[1017]: info: Mar 31 14:09:39 volumio3118d volumio[1017]: ---------------------------- MPD announces system playlist update Mar 31 14:09:39 volumio3118d volumio[1017]: info: Ignoring MPD Status Update Mar 31 14:09:39 volumio3118d volumio[1017]: info: Mar 31 14:09:39 volumio3118d volumio[1017]: ---------------------------- MPD announces state update: player Mar 31 14:09:39 volumio3118d volumio[1017]: info: ControllerMpd::getState Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand status Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand status took 8 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: info: ------------------------------ 7ms Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand status took 7 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: info: ------------------------------ 6ms Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand status took 7 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::parseState Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::parseState Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::parseState Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand clearerror took 7 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand clearerror took 6 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand clearerror took 6 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::parseTrackInfo Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::parseTrackInfo Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: ControllerMpd::parseTrackInfo Mar 31 14:09:39 volumio3118d volumio[1017]: info: ControllerMpd::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::servicePushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioPushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Original Sin","artist":"INXS","album":"The Swing","uri":"NAS/nekosynology/Music 1/New folder/INXS - The Swing (1984) [2014 _AcousticSounds 24-192]/01-Original Sin.flac","trackType":"flac"} Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: CURRENT POSITION 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::syncState stateService stop Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::syncState currentStatus stop Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioPushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: No code Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioPushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: ControllerMpd::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::servicePushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioPushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Original Sin","artist":"INXS","album":"The Swing","uri":"NAS/nekosynology/Music 1/New folder/INXS - The Swing (1984) [2014 _AcousticSounds 24-192]/01-Original Sin.flac","trackType":"flac"} Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: CURRENT POSITION 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::syncState stateService stop Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::syncState currentStatus stop Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioPushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: No code Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioPushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: ControllerMpd::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::servicePushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioPushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Original Sin","artist":"INXS","album":"The Swing","uri":"NAS/nekosynology/Music 1/New folder/INXS - The Swing (1984) [2014 _AcousticSounds 24-192]/01-Original Sin.flac","trackType":"flac"} Mar 31 14:09:39 volumio3118d volumio[1017]: verbose: CURRENT POSITION 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::syncState stateService stop Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::syncState currentStatus stop Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioPushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: No code Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreStateMachine::pushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:09:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioPushState Mar 31 14:09:39 volumio3118d volumio[1017]: info: ------------------------------ 51ms Mar 31 14:09:39 volumio3118d volumio[1017]: info: ------------------------------ 49ms Mar 31 14:09:39 volumio3118d volumio[1017]: info: ------------------------------ 48ms Mar 31 14:09:41 volumio3118d volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Mar 31 14:09:42 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 31 14:09:42 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 31 14:09:43 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetQueue Mar 31 14:09:43 volumio3118d volumio[1017]: info: CoreStateMachine::getQueue Mar 31 14:09:43 volumio3118d volumio[1017]: info: CorePlayQueue::getQueue Mar 31 14:09:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 31 14:09:48 volumio3118d ntpd[780]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 31 14:09:48 volumio3118d go-librespot[1845]: time="2026-03-31T14:09:48+08: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" Mar 31 14:09:48 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:09:48 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:09:52 volumio3118d systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 31 14:09:52 volumio3118d systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Mar 31 14:09:52 volumio3118d systemd[1]: Stopped go-librespot Daemon. Mar 31 14:09:52 volumio3118d systemd[1]: Started go-librespot Daemon. Mar 31 14:09:52 volumio3118d go-librespot[1945]: go-librespot daemon starting... Mar 31 14:09:52 volumio3118d go-librespot[1945]: time="2026-03-31T14:09:52+08:00" level=info msg="running go-librespot 0.4.0" Mar 31 14:09:52 volumio3118d go-librespot[1945]: time="2026-03-31T14:09:52+08:00" level=debug msg="app state loaded" Mar 31 14:09:52 volumio3118d go-librespot[1945]: time="2026-03-31T14:09:52+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 31 14:09:57 volumio3118d volumio[1017]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 14:09:57 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 31 14:09:57 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Mar 31 14:09:57 volumio3118d sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Mar 31 14:09:57 volumio3118d sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:09:57 volumio3118d sudo[1975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 31 14:09:57 volumio3118d sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:09:57 volumio3118d sudo[1975]: pam_unix(sudo:session): session closed for user root Mar 31 14:09:57 volumio3118d sudo[1970]: pam_unix(sudo:session): session closed for user root Mar 31 14:09:57 volumio3118d sudo[1982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 31 14:09:57 volumio3118d sudo[1982]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:09:57 volumio3118d sudo[1989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 31 14:09:57 volumio3118d sudo[1982]: pam_unix(sudo:session): session closed for user root Mar 31 14:09:57 volumio3118d sudo[1989]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:09:57 volumio3118d sudo[1989]: pam_unix(sudo:session): session closed for user root Mar 31 14:09:57 volumio3118d sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 14:09:57 volumio3118d sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:09:57 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 31 14:09:57 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 31 14:09:57 volumio3118d sudo[1995]: pam_unix(sudo:session): session closed for user root Mar 31 14:09:57 volumio3118d sudo[1998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:09:57 volumio3118d sudo[1998]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:09:57 volumio3118d sudo[1998]: pam_unix(sudo:session): session closed for user root Mar 31 14:09:57 volumio3118d sudo[2002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 31 14:09:57 volumio3118d sudo[2002]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:01 volumio3118d volumio-time-update[601]: volumio-time-update-util: Date not found in response Mar 31 14:10:01 volumio3118d volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Mar 31 14:10:01 volumio3118d sudo[2002]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:02 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 31 14:10:06 volumio3118d volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Mar 31 14:10:08 volumio3118d ntpd[780]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 31 14:10:12 volumio3118d go-librespot[1945]: time="2026-03-31T14:10:12+08: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" Mar 31 14:10:12 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:10:12 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:10:15 volumio3118d systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 31 14:10:15 volumio3118d systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Mar 31 14:10:15 volumio3118d systemd[1]: Stopped go-librespot Daemon. Mar 31 14:10:15 volumio3118d systemd[1]: Started go-librespot Daemon. Mar 31 14:10:15 volumio3118d go-librespot[2041]: go-librespot daemon starting... Mar 31 14:10:15 volumio3118d go-librespot[2041]: time="2026-03-31T14:10:15+08:00" level=info msg="running go-librespot 0.4.0" Mar 31 14:10:15 volumio3118d go-librespot[2041]: time="2026-03-31T14:10:15+08:00" level=debug msg="app state loaded" Mar 31 14:10:15 volumio3118d go-librespot[2041]: time="2026-03-31T14:10:15+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 31 14:10:19 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Mar 31 14:10:19 volumio3118d volumio[1017]: info: Saving new wireless network Mar 31 14:10:19 volumio3118d sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Mar 31 14:10:19 volumio3118d sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:19 volumio3118d sudo[2049]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:19 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 31 14:10:19 volumio3118d volumio[1017]: info: Discovery: Restarting Advertising due to device name change Mar 31 14:10:19 volumio3118d sudo[2052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 31 14:10:19 volumio3118d sudo[2052]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:19 volumio3118d dhcpcd[901]: received SIGTERM, stopping Mar 31 14:10:19 volumio3118d dhcpcd[901]: wlan0: removing interface Mar 31 14:10:19 volumio3118d systemd[1]: Stopping Wireless Services... Mar 31 14:10:19 volumio3118d avahi-daemon[598]: Withdrawing address record for 169.254.189.206 on wlan0. Mar 31 14:10:19 volumio3118d dhcpcd[901]: wlan0: deleting route to 169.254.0.0/16 Mar 31 14:10:19 volumio3118d dhcpcd[901]: wlan0: deleting default route Mar 31 14:10:19 volumio3118d dhcpcd[901]: eth0: removing interface Mar 31 14:10:19 volumio3118d dhcpcd[901]: dhcpcd exited Mar 31 14:10:19 volumio3118d systemd[1]: wireless.service: Succeeded. Mar 31 14:10:19 volumio3118d systemd[1]: Stopped Wireless Services. Mar 31 14:10:19 volumio3118d systemd[1]: Starting Wireless Services... Mar 31 14:10:19 volumio3118d wireless.js[2075]: Cleaning previous... Mar 31 14:10:19 volumio3118d systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 31 14:10:20 volumio3118d volumio[1017]: info: Discovery: A device disappeared from network Mar 31 14:10:20 volumio3118d volumio[1017]: info: Discovery: Device volumio3118d disappeared from network Mar 31 14:10:20 volumio3118d sudo[2089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:20 volumio3118d sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:20 volumio3118d sudo[2089]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:20 volumio3118d systemd[1]: hostapd.service: Succeeded. Mar 31 14:10:20 volumio3118d systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 31 14:10:20 volumio3118d sudo[2092]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 31 14:10:20 volumio3118d sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:20 volumio3118d avahi-daemon[598]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 31 14:10:20 volumio3118d avahi-daemon[598]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 31 14:10:20 volumio3118d avahi-daemon[598]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 31 14:10:20 volumio3118d sudo[2092]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:20 volumio3118d sudo[2094]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 31 14:10:20 volumio3118d sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:20 volumio3118d go-librespot[2041]: time="2026-03-31T14:10:20+08: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" Mar 31 14:10:20 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:10:20 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:10:20 volumio3118d sudo[2094]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:20 volumio3118d wireless.js[2075]: Stopped aP Mar 31 14:10:20 volumio3118d sudo[2101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 31 14:10:20 volumio3118d sudo[2101]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:20 volumio3118d sudo[2101]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:20 volumio3118d kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 31 14:10:20 volumio3118d sudo[2103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 31 14:10:20 volumio3118d sudo[2103]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:20 volumio3118d sudo[2103]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:20 volumio3118d sudo[2110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 31 14:10:20 volumio3118d sudo[2110]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:20 volumio3118d sudo[2110]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:20 volumio3118d sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 31 14:10:20 volumio3118d sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:21 volumio3118d sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:21 volumio3118d sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:21 volumio3118d sudo[2117]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:21 volumio3118d volumio-time-update[601]: volumio-time-update-util: Date not found in response Mar 31 14:10:21 volumio3118d volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Mar 31 14:10:22 volumio3118d sudo[2121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:22 volumio3118d sudo[2121]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:22 volumio3118d sudo[2121]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 31 14:10:22 volumio3118d ntpd[780]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=250 secs Mar 31 14:10:22 volumio3118d ntpd[780]: Deleting interface #4 wlan0, 169.254.189.206#123, interface stats: received=0, sent=0, dropped=0, active_time=239 secs Mar 31 14:10:22 volumio3118d volumio[1017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 31 14:10:22 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 31 14:10:22 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:22 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:23 volumio3118d sudo[2124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:23 volumio3118d sudo[2124]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:23 volumio3118d sudo[2124]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:23 volumio3118d ntpd[780]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 31 14:10:23 volumio3118d ntpd[780]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 31 14:10:23 volumio3118d ntpd[780]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 31 14:10:23 volumio3118d ntpd[780]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 31 14:10:23 volumio3118d ntpd[780]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 31 14:10:23 volumio3118d ntpd[780]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 31 14:10:23 volumio3118d systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 31 14:10:23 volumio3118d systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Mar 31 14:10:23 volumio3118d systemd[1]: Stopped go-librespot Daemon. Mar 31 14:10:23 volumio3118d systemd[1]: Started go-librespot Daemon. Mar 31 14:10:23 volumio3118d go-librespot[2126]: go-librespot daemon starting... Mar 31 14:10:23 volumio3118d go-librespot[2126]: time="2026-03-31T14:10:23+08:00" level=info msg="running go-librespot 0.4.0" Mar 31 14:10:23 volumio3118d go-librespot[2126]: time="2026-03-31T14:10:23+08:00" level=debug msg="app state loaded" Mar 31 14:10:23 volumio3118d go-librespot[2126]: time="2026-03-31T14:10:23+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 31 14:10:23 volumio3118d go-librespot[2126]: time="2026-03-31T14:10:23+08: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" Mar 31 14:10:23 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:10:23 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:10:23 volumio3118d sudo[2112]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:23 volumio3118d wireless.js[2075]: SETTING APPROPRIATE REG DOMAIN: US Mar 31 14:10:23 volumio3118d sudo[2135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 31 14:10:23 volumio3118d sudo[2135]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:23 volumio3118d sudo[2135]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:23 volumio3118d sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Mar 31 14:10:23 volumio3118d sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:23 volumio3118d sudo[2137]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:23 volumio3118d wireless.js[2075]: SUCCESSFULLY SET NEW REGDOMAIN: US Mar 31 14:10:23 volumio3118d wireless.js[2075]: Start wireless flow Mar 31 14:10:23 volumio3118d wireless.js[2075]: Stopped hotspot (if there).. Mar 31 14:10:23 volumio3118d sudo[2142]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 31 14:10:23 volumio3118d sudo[2142]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:23 volumio3118d sudo[2142]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:23 volumio3118d sudo[2144]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 31 14:10:23 volumio3118d sudo[2144]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:24 volumio3118d volumio[1017]: info: Discovery: Started advertising with name: Volumio3118D Mar 31 14:10:24 volumio3118d sudo[2144]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:24 volumio3118d sudo[2148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:24 volumio3118d sudo[2148]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:24 volumio3118d wireless.js[2075]: DHCP IP Mar 31 14:10:24 volumio3118d wireless.js[2075]: Start ap Mar 31 14:10:24 volumio3118d sudo[2148]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:24 volumio3118d wpa_supplicant[2149]: Successfully initialized wpa_supplicant Mar 31 14:10:24 volumio3118d sudo[2151]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 31 14:10:24 volumio3118d sudo[2151]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:24 volumio3118d kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 31 14:10:24 volumio3118d dhcpcd[2152]: dev: loaded udev Mar 31 14:10:24 volumio3118d dhcpcd-run-hooks[2170]: wlan0: starting wpa_supplicant Mar 31 14:10:24 volumio3118d dhcpcd-run-hooks[2175]: wlan0: failed to start wpa_supplicant Mar 31 14:10:24 volumio3118d dhcpcd-run-hooks[2176]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Mar 31 14:10:24 volumio3118d dhcpcd[2152]: wlan0: connected to Access Point `' Mar 31 14:10:24 volumio3118d dhcpcd[2152]: no interfaces have a carrier Mar 31 14:10:24 volumio3118d dhcpcd[2152]: forked to background, child pid 2187 Mar 31 14:10:24 volumio3118d dhcpcd[2187]: eth0: waiting for carrier Mar 31 14:10:24 volumio3118d dhcpcd[2187]: wlan0: waiting for carrier Mar 31 14:10:24 volumio3118d sudo[2151]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:25 volumio3118d sudo[2203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:25 volumio3118d sudo[2203]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:25 volumio3118d sudo[2203]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:25 volumio3118d volumio[1017]: info: Initializing connection to go-librespot Websocket Mar 31 14:10:25 volumio3118d volumio[1017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 31 14:10:25 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:25 volumio3118d sudo[2206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:25 volumio3118d sudo[2206]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:25 volumio3118d sudo[2206]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:26 volumio3118d sudo[2209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:26 volumio3118d sudo[2209]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:26 volumio3118d sudo[2209]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:26 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Mar 31 14:10:26 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:26 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:26 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:26 volumio3118d sudo[2212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:26 volumio3118d sudo[2212]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:26 volumio3118d sudo[2212]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:26 volumio3118d volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Mar 31 14:10:26 volumio3118d volumio-time-update[601]: volumio-time-update-util: Date not found in response Mar 31 14:10:26 volumio3118d volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Mar 31 14:10:26 volumio3118d systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 31 14:10:26 volumio3118d systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Mar 31 14:10:26 volumio3118d systemd[1]: Stopped go-librespot Daemon. Mar 31 14:10:26 volumio3118d systemd[1]: Started go-librespot Daemon. Mar 31 14:10:26 volumio3118d go-librespot[2219]: go-librespot daemon starting... Mar 31 14:10:27 volumio3118d go-librespot[2219]: time="2026-03-31T14:10:27+08:00" level=info msg="running go-librespot 0.4.0" Mar 31 14:10:27 volumio3118d go-librespot[2219]: time="2026-03-31T14:10:27+08:00" level=debug msg="app state loaded" Mar 31 14:10:27 volumio3118d go-librespot[2219]: time="2026-03-31T14:10:27+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 31 14:10:27 volumio3118d go-librespot[2219]: time="2026-03-31T14:10:27+08: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" Mar 31 14:10:27 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:10:27 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:10:27 volumio3118d sudo[2227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:27 volumio3118d sudo[2227]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:27 volumio3118d sudo[2227]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:27 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:27 volumio3118d sudo[2230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:27 volumio3118d sudo[2230]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:27 volumio3118d sudo[2230]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:28 volumio3118d sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:28 volumio3118d sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:28 volumio3118d sudo[2236]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:28 volumio3118d volumio[1017]: info: Initializing connection to go-librespot Websocket Mar 31 14:10:28 volumio3118d volumio[1017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 31 14:10:28 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:28 volumio3118d sudo[2239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:28 volumio3118d sudo[2239]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:28 volumio3118d sudo[2239]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:28 volumio3118d kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 31 14:10:28 volumio3118d kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 31 14:10:28 volumio3118d kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 31 14:10:28 volumio3118d kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 31 14:10:29 volumio3118d sudo[2242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:29 volumio3118d sudo[2242]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:29 volumio3118d sudo[2242]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:29 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:29 volumio3118d sudo[2245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:29 volumio3118d sudo[2245]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:29 volumio3118d sudo[2245]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:30 volumio3118d systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 31 14:10:30 volumio3118d systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Mar 31 14:10:30 volumio3118d systemd[1]: Stopped go-librespot Daemon. Mar 31 14:10:30 volumio3118d sudo[2249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:30 volumio3118d sudo[2249]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:30 volumio3118d sudo[2249]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:30 volumio3118d systemd[1]: Started go-librespot Daemon. Mar 31 14:10:30 volumio3118d go-librespot[2247]: go-librespot daemon starting... Mar 31 14:10:30 volumio3118d go-librespot[2247]: time="2026-03-31T14:10:30+08:00" level=info msg="running go-librespot 0.4.0" Mar 31 14:10:30 volumio3118d go-librespot[2247]: time="2026-03-31T14:10:30+08:00" level=debug msg="app state loaded" Mar 31 14:10:30 volumio3118d go-librespot[2247]: time="2026-03-31T14:10:30+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 31 14:10:30 volumio3118d go-librespot[2247]: time="2026-03-31T14:10:30+08: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" Mar 31 14:10:30 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:10:30 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:10:30 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:30 volumio3118d sudo[2258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:30 volumio3118d sudo[2258]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:30 volumio3118d sudo[2258]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:31 volumio3118d sudo[2261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:31 volumio3118d sudo[2261]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:31 volumio3118d sudo[2261]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:31 volumio3118d volumio[1017]: info: Initializing connection to go-librespot Websocket Mar 31 14:10:31 volumio3118d volumio[1017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 31 14:10:31 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:31 volumio3118d sudo[2264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:31 volumio3118d sudo[2264]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:31 volumio3118d sudo[2264]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:31 volumio3118d wpa_supplicant[2174]: wlan0: Trying to associate with SSID 'nekostudio' Mar 31 14:10:31 volumio3118d dhcpcd[2187]: wlan0: carrier acquired Mar 31 14:10:31 volumio3118d dhcpcd[2187]: wlan0: connected to Access Point `nekostudio' Mar 31 14:10:31 volumio3118d wpa_supplicant[2174]: wlan0: Associated with 12:7c:61:9a:67:62 Mar 31 14:10:31 volumio3118d wpa_supplicant[2174]: wlan0: CTRL-EVENT-CONNECTED - Connection to 12:7c:61:9a:67:62 completed [id=0 id_str=] Mar 31 14:10:31 volumio3118d wpa_supplicant[2174]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 31 14:10:31 volumio3118d wpa_supplicant[2174]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Mar 31 14:10:31 volumio3118d dhcpcd[2187]: DUID 00:01:00:01:2e:7c:59:77:b8:27:eb:a1:a4:62 Mar 31 14:10:31 volumio3118d dhcpcd[2187]: wlan0: IAID dd:b8:bd:de Mar 31 14:10:31 volumio3118d dhcpcd[2187]: wlan0: adding address fe80::da3a:ddff:feb8:bdde Mar 31 14:10:31 volumio3118d dhcpcd[2187]: ipv6_addaddr1: Permission denied Mar 31 14:10:31 volumio3118d dhcpcd[2187]: wlan0: carrier lost Mar 31 14:10:31 volumio3118d dhcpcd[2187]: wlan0: carrier acquired Mar 31 14:10:31 volumio3118d volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Mar 31 14:10:31 volumio3118d dhcpcd[2187]: wlan0: IAID dd:b8:bd:de Mar 31 14:10:31 volumio3118d volumio-time-update[601]: volumio-time-update-util: Date not found in response Mar 31 14:10:31 volumio3118d volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Mar 31 14:10:31 volumio3118d dhcpcd[2187]: wlan0: rebinding lease of 192.168.1.100 Mar 31 14:10:32 volumio3118d dhcpcd[2187]: wlan0: NAK: from 192.168.1.1 Mar 31 14:10:32 volumio3118d dhcpcd[2187]: wlan0: soliciting a DHCP lease Mar 31 14:10:32 volumio3118d sudo[2295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:32 volumio3118d sudo[2295]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:32 volumio3118d sudo[2295]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:32 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:32 volumio3118d sudo[2299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:32 volumio3118d sudo[2299]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:32 volumio3118d sudo[2299]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:32 volumio3118d dhcpcd[2187]: wlan0: soliciting an IPv6 router Mar 31 14:10:32 volumio3118d dhcpcd[2187]: wlan0: offered 192.168.1.127 from 192.168.1.1 Mar 31 14:10:33 volumio3118d sudo[2302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:33 volumio3118d sudo[2302]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:33 volumio3118d sudo[2302]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:33 volumio3118d dhcpcd[2187]: wlan0: probing address 192.168.1.127/24 Mar 31 14:10:33 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:33 volumio3118d sudo[2305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:33 volumio3118d sudo[2305]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:33 volumio3118d sudo[2305]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:33 volumio3118d systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 31 14:10:33 volumio3118d systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Mar 31 14:10:33 volumio3118d systemd[1]: Stopped go-librespot Daemon. Mar 31 14:10:33 volumio3118d systemd[1]: Started go-librespot Daemon. Mar 31 14:10:33 volumio3118d go-librespot[2307]: go-librespot daemon starting... Mar 31 14:10:33 volumio3118d go-librespot[2307]: time="2026-03-31T14:10:33+08:00" level=info msg="running go-librespot 0.4.0" Mar 31 14:10:33 volumio3118d go-librespot[2307]: time="2026-03-31T14:10:33+08:00" level=debug msg="app state loaded" Mar 31 14:10:33 volumio3118d go-librespot[2307]: time="2026-03-31T14:10:33+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 31 14:10:33 volumio3118d go-librespot[2307]: time="2026-03-31T14:10:33+08: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" Mar 31 14:10:33 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:10:33 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:10:34 volumio3118d sudo[2316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:34 volumio3118d sudo[2316]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:34 volumio3118d sudo[2316]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:34 volumio3118d volumio[1017]: info: Initializing connection to go-librespot Websocket Mar 31 14:10:34 volumio3118d volumio[1017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 31 14:10:34 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:34 volumio3118d sudo[2319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:34 volumio3118d sudo[2319]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:34 volumio3118d sudo[2319]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:35 volumio3118d sudo[2336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:35 volumio3118d sudo[2336]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:35 volumio3118d sudo[2336]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:35 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:35 volumio3118d sudo[2339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:35 volumio3118d sudo[2339]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:35 volumio3118d sudo[2339]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:36 volumio3118d sudo[2342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:36 volumio3118d sudo[2342]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:36 volumio3118d sudo[2342]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:36 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:36 volumio3118d sudo[2345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:36 volumio3118d sudo[2345]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:36 volumio3118d sudo[2345]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:36 volumio3118d volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Mar 31 14:10:36 volumio3118d volumio-time-update[601]: volumio-time-update-util: Date not found in response Mar 31 14:10:36 volumio3118d volumio-time-update[601]: volumio-time-update-util: Retrying in 5 seconds... Mar 31 14:10:36 volumio3118d systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 31 14:10:36 volumio3118d systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Mar 31 14:10:36 volumio3118d systemd[1]: Stopped go-librespot Daemon. Mar 31 14:10:36 volumio3118d systemd[1]: Started go-librespot Daemon. Mar 31 14:10:36 volumio3118d go-librespot[2352]: go-librespot daemon starting... Mar 31 14:10:36 volumio3118d go-librespot[2352]: time="2026-03-31T14:10:36+08:00" level=info msg="running go-librespot 0.4.0" Mar 31 14:10:36 volumio3118d go-librespot[2352]: time="2026-03-31T14:10:36+08:00" level=debug msg="app state loaded" Mar 31 14:10:36 volumio3118d go-librespot[2352]: time="2026-03-31T14:10:36+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 31 14:10:36 volumio3118d go-librespot[2352]: time="2026-03-31T14:10:36+08: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" Mar 31 14:10:36 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:10:36 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:10:37 volumio3118d sudo[2361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:37 volumio3118d sudo[2361]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:37 volumio3118d sudo[2361]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:37 volumio3118d volumio[1017]: info: Initializing connection to go-librespot Websocket Mar 31 14:10:37 volumio3118d volumio[1017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 31 14:10:37 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:37 volumio3118d sudo[2364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:37 volumio3118d sudo[2364]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:37 volumio3118d sudo[2364]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:38 volumio3118d sudo[2367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:38 volumio3118d sudo[2367]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:38 volumio3118d sudo[2367]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:38 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:38 volumio3118d sudo[2370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:38 volumio3118d sudo[2370]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:38 volumio3118d sudo[2370]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:38 volumio3118d dhcpcd[2187]: wlan0: leased 192.168.1.127 for 7200 seconds Mar 31 14:10:38 volumio3118d avahi-daemon[598]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.127. Mar 31 14:10:38 volumio3118d dhcpcd[2187]: wlan0: adding route to 192.168.1.0/24 Mar 31 14:10:38 volumio3118d dhcpcd[2187]: wlan0: adding default route via 192.168.1.1 Mar 31 14:10:38 volumio3118d avahi-daemon[598]: New relevant interface wlan0.IPv4 for mDNS. Mar 31 14:10:38 volumio3118d avahi-daemon[598]: Registering new address record for 192.168.1.127 on wlan0.IPv4. Mar 31 14:10:38 volumio3118d dnsmasq[1007]: reading /etc/resolv.conf Mar 31 14:10:38 volumio3118d dnsmasq[1007]: using nameserver 192.168.1.1#53 Mar 31 14:10:38 volumio3118d dnsmasq[1007]: using nameserver 208.67.222.222#53 Mar 31 14:10:38 volumio3118d dnsmasq[1007]: using nameserver 208.67.220.220#53 Mar 31 14:10:39 volumio3118d sudo[2395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:39 volumio3118d sudo[2395]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:39 volumio3118d sudo[2395]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:39 volumio3118d sudo[2398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 31 14:10:39 volumio3118d sudo[2398]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:39 volumio3118d sudo[2398]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:39 volumio3118d wireless.js[2075]: trying... Mar 31 14:10:39 volumio3118d sudo[2404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 31 14:10:39 volumio3118d sudo[2404]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 14:10:39 volumio3118d volumio[1017]: info: Discovery: Getting this device information Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 14:10:39 volumio3118d sudo[2404]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:39 volumio3118d wireless.js[2075]: Connected to: ----nekostudio Mar 31 14:10:39 volumio3118d wireless.js[2075]: ---- Mar 31 14:10:39 volumio3118d volumio[1017]: verbose: New Socket.io Connection to 192.168.1.127:3000 from 192.168.1.116 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 15 Mar 31 14:10:39 volumio3118d sudo[2407]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:39 volumio3118d sudo[2407]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 31 14:10:39 volumio3118d sudo[2407]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:39 volumio3118d wireless.js[2075]: ... joined AP, wlan0 IPv4 is 192.168.1.127, ipV6 is undefined Mar 31 14:10:39 volumio3118d wireless.js[2075]: It's done! AP Mar 31 14:10:39 volumio3118d volumio[1017]: info: Discovery: adding 84979073-a15d-4fe9-bacb-fb1c35255497 Mar 31 14:10:39 volumio3118d volumio[1017]: info: Discovery: Found device Volumio3118D Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:39 volumio3118d volumio[1017]: verbose: New Socket.io Connection to 192.168.1.127:3000 from 192.168.1.116 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 15 Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 14:10:39 volumio3118d volumio[1017]: info: Discovery: Getting this device information Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:39 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:39 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 14:10:39 volumio3118d kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 31 14:10:39 volumio3118d systemd[1]: Started Wireless Services. Mar 31 14:10:39 volumio3118d sudo[2052]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:39 volumio3118d systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 31 14:10:39 volumio3118d systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Mar 31 14:10:39 volumio3118d systemd[1]: Stopped go-librespot Daemon. Mar 31 14:10:39 volumio3118d systemd[1]: Started go-librespot Daemon. Mar 31 14:10:39 volumio3118d go-librespot[2412]: go-librespot daemon starting... Mar 31 14:10:40 volumio3118d go-librespot[2412]: time="2026-03-31T14:10:40+08:00" level=info msg="running go-librespot 0.4.0" Mar 31 14:10:40 volumio3118d go-librespot[2412]: time="2026-03-31T14:10:40+08:00" level=debug msg="app state loaded" Mar 31 14:10:40 volumio3118d go-librespot[2412]: time="2026-03-31T14:10:40+08:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 31 14:10:40 volumio3118d ntpd[780]: Listen normally on 5 wlan0 192.168.1.127:123 Mar 31 14:10:40 volumio3118d ntpd[780]: new interface(s) found: waking up resolver Mar 31 14:10:40 volumio3118d volumio[1017]: info: Initializing connection to go-librespot Websocket Mar 31 14:10:40 volumio3118d go-librespot[2412]: time="2026-03-31T14:10:40+08:00" level=debug msg="new websocket client" Mar 31 14:10:40 volumio3118d volumio[1017]: info: Connection to go-librespot Websocket established Mar 31 14:10:41 volumio3118d volumio-time-update[601]: volumio-time-update-util: Fetching time from Volumio... Mar 31 14:10:43 volumio3118d volumio[1017]: info: Getting Spotify volume Mar 31 14:10:43 volumio3118d volumio[1017]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Mar 31 14:10:43 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:43 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 14:10:44 volumio3118d volumio[1017]: info: Discovery: Getting this device information Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:44 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Mar 31 14:10:44 volumio3118d sudo[2464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Mar 31 14:10:44 volumio3118d sudo[2464]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:44 volumio3118d sudo[2464]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:44 volumio3118d sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 31 14:10:44 volumio3118d sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:44 volumio3118d sudo[2469]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:44 volumio3118d sudo[2478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 31 14:10:44 volumio3118d sudo[2478]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:44 volumio3118d sudo[2478]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:44 volumio3118d sudo[2484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 31 14:10:44 volumio3118d sudo[2484]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:44 volumio3118d sudo[2484]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:44 volumio3118d sudo[2489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 14:10:44 volumio3118d sudo[2489]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:44 volumio3118d sudo[2489]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:44 volumio3118d sudo[2492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:44 volumio3118d sudo[2492]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:44 volumio3118d sudo[2492]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 14:10:44 volumio3118d volumio[1017]: info: Discovery: Getting this device information Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:44 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 14:10:44 volumio3118d volumio[1017]: info: Discovery: Getting this device information Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:44 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:44 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 31 14:10:44 volumio3118d volumio[1017]: verbose: New Socket.io Connection to 192.168.1.127:3000 from 192.168.1.116 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 16 Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Mar 31 14:10:44 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Mar 31 14:10:44 volumio3118d sudo[2496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:44 volumio3118d sudo[2496]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:44 volumio3118d sudo[2496]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:44 volumio3118d sudo[2499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 31 14:10:44 volumio3118d sudo[2499]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:44 volumio3118d sudo[2499]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:45 volumio3118d volumio[1017]: info: Volumio Network Manager: Network status updated: 2 Mar 31 14:10:45 volumio3118d go-librespot[2412]: time="2026-03-31T14:10:45+08: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\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2026-03-31T14:10:45+08:00 is before 2026-07-09T00:00:00Z" Mar 31 14:10:45 volumio3118d systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 31 14:10:45 volumio3118d systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 31 14:10:45 volumio3118d volumio[1017]: (node:1017) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 31 14:10:45 volumio3118d volumio[1017]: at connResetException (internal/errors.js:607:14) Mar 31 14:10:45 volumio3118d volumio[1017]: at Socket.socketOnEnd (_http_client.js:493:23) Mar 31 14:10:45 volumio3118d volumio[1017]: at Socket.emit (events.js:327:22) Mar 31 14:10:45 volumio3118d volumio[1017]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 31 14:10:45 volumio3118d volumio[1017]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 31 14:10:45 volumio3118d volumio[1017]: (node:1017) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Mar 31 14:10:45 volumio3118d volumio[1017]: info: Connection to go-librespot Websocket closed Mar 31 14:10:45 volumio3118d sudo[2521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 14:10:45 volumio3118d sudo[2521]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:45 volumio3118d sudo[2521]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:45 volumio3118d sudo[2524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:45 volumio3118d sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:45 volumio3118d sudo[2524]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:45 volumio3118d volumio[1017]: verbose: New Socket.io Connection to 192.168.1.127 from 192.168.1.116 UA: Mozilla/5.0 (Linux; Android 16; 2312DRA50G Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 16 Mar 31 14:10:45 volumio3118d sudo[2527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 31 14:10:45 volumio3118d sudo[2527]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:45 volumio3118d sudo[2527]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:45 volumio3118d sudo[2529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 31 14:10:45 volumio3118d sudo[2529]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:45 volumio3118d sudo[2529]: pam_unix(sudo:session): session closed for user root Mar 31 14:10:46 volumio3118d volumio[1017]: verbose: New Socket.io Connection to 192.168.1.127 from 192.168.1.116 UA: Mozilla/5.0 (Linux; Android 16; 2312DRA50G Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 17 Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetVisibleSources Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:46 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 31 14:10:46 volumio3118d volumio[1017]: info: Received Get System Info Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 31 14:10:46 volumio3118d volumio[1017]: info: Discovery: Getting this device information Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:46 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::volumioGetState Mar 31 14:10:46 volumio3118d volumio[1017]: info: CorePlayQueue::getTrack 0 Mar 31 14:10:46 volumio3118d volumio[1017]: info: Listing playlists Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 31 14:10:46 volumio3118d volumio[1017]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 31 14:10:46 volumio3118d volumio[1017]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 14:10:46 volumio3118d volumio[1017]: Error: certificate is not yet valid Mar 31 14:10:46 volumio3118d volumio[1017]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Mar 31 14:10:46 volumio3118d volumio[1017]: at TLSSocket.emit (events.js:315:20) Mar 31 14:10:46 volumio3118d volumio[1017]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Mar 31 14:10:46 volumio3118d volumio[1017]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Mar 31 14:10:46 volumio3118d volumio[1017]: code: 'CERT_NOT_YET_VALID' Mar 31 14:10:46 volumio3118d volumio[1017]: } Mar 31 14:10:46 volumio3118d volumio[1017]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 14:10:47 volumio3118d volumio-time-update[601]: volumio-time-update-util: Setting system time to: 2026-10-12 15:57:15 Mar 31 14:10:47 volumio3118d sudo[2541]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-10-12 15:57:15 Mar 31 14:10:47 volumio3118d sudo[2541]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 31 14:10:47 volumio3118d dbus-daemon[579]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.15' (uid=0 pid=2542 comm="timedatectl set-time 2026-10-12 15:57:15 ") Mar 31 14:10:47 volumio3118d systemd[1]: Starting Time & Date Service... Mar 31 14:10:47 volumio3118d sudo[2545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-31 14:09 Mar 31 14:10:47 volumio3118d sudo[2545]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"