-- Logs begin at Mon 2024-07-22 14:21:29 BST, end at Thu 2025-12-11 14:13:01 GMT. -- Dec 11 14:12:00 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:00 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Dec 11 14:12:00 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:00 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:00 arpal go-librespot[2545]: go-librespot daemon starting... Dec 11 14:12:00 arpal go-librespot[2545]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:00 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:00 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::volumioGetBrowseSources Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 14:12:01 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:01 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:01 arpal volumio[2227]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 11 14:12:01 arpal volumio[2227]: info: Completed starting MyVolumio Plugin Dec 11 14:12:01 arpal volumio[2227]: info: BOOT COMPLETED Dec 11 14:12:01 arpal volumio[2227]: [Metrics] CommandRouter: 39s 712.13ms Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::volumiosetStartupVolume Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::Close All Modals sent Dec 11 14:12:01 arpal volumio[2227]: info: CoreCommandRouter::Close All Modals sent Dec 11 14:12:01 arpal volumio[2227]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 11 14:12:01 arpal volumio[2227]: aplay: main:828: audio open error: Device or resource busy Dec 11 14:12:02 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 11 14:12:02 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 14:12:02 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 11 14:12:03 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:03 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Dec 11 14:12:03 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:03 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:03 arpal go-librespot[2549]: go-librespot daemon starting... Dec 11 14:12:03 arpal go-librespot[2549]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:03 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:03 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:04 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:04 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:06 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:06 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Dec 11 14:12:06 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:06 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:06 arpal go-librespot[2551]: go-librespot daemon starting... Dec 11 14:12:06 arpal go-librespot[2551]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:06 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:06 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:07 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:07 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:09 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:09 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Dec 11 14:12:09 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:10 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:10 arpal go-librespot[2553]: go-librespot daemon starting... Dec 11 14:12:10 arpal go-librespot[2553]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:10 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:10 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:10 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:10 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:11 arpal volumio[2227]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.238 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 11 14:12:11 arpal volumio[2227]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.238 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 11 14:12:11 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:11 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: Received Get System Info Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 14:12:12 arpal volumio[2227]: info: Discovery: Getting this device information Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetQueue Dec 11 14:12:12 arpal volumio[2227]: info: CoreStateMachine::getQueue Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getQueue Dec 11 14:12:12 arpal volumio[2227]: info: Listing playlists Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 14:12:12 arpal volumio[2227]: info: Received Get System Info Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 14:12:12 arpal volumio[2227]: info: Discovery: Getting this device information Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: Received Get System Info Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 14:12:12 arpal volumio[2227]: info: Discovery: Getting this device information Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 14:12:12 arpal volumio[2227]: info: Received Get System Info Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 14:12:12 arpal volumio[2227]: info: Discovery: Getting this device information Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:12 arpal volumio[2227]: info: Listing playlists Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::volumioGetQueue Dec 11 14:12:12 arpal volumio[2227]: info: CoreStateMachine::getQueue Dec 11 14:12:12 arpal volumio[2227]: info: CorePlayQueue::getQueue Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 14:12:12 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 14:12:13 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:13 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Dec 11 14:12:13 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:13 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:13 arpal go-librespot[2555]: go-librespot daemon starting... Dec 11 14:12:13 arpal go-librespot[2555]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:13 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:13 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:13 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:13 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:16 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:16 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:16 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:16 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Dec 11 14:12:16 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:16 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:16 arpal go-librespot[2557]: go-librespot daemon starting... Dec 11 14:12:16 arpal go-librespot[2557]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:16 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:16 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:19 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:19 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:19 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:19 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Dec 11 14:12:19 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:19 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:19 arpal go-librespot[2560]: go-librespot daemon starting... Dec 11 14:12:19 arpal go-librespot[2560]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:19 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:19 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:22 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:22 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:22 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:22 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Dec 11 14:12:22 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:23 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:23 arpal go-librespot[2562]: go-librespot daemon starting... Dec 11 14:12:23 arpal go-librespot[2562]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:23 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:23 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:25 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:25 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:26 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:26 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Dec 11 14:12:26 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:26 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:26 arpal go-librespot[2564]: go-librespot daemon starting... Dec 11 14:12:26 arpal go-librespot[2564]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:26 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:26 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:28 arpal volumio[2227]: verbose: New Socket.io Connection to 192.168.10.170 from 192.168.10.171 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 11 14:12:28 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:28 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:28 arpal volumio[2227]: info: Received Get System Info Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 14:12:28 arpal volumio[2227]: info: Discovery: Getting this device information Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:28 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:28 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:28 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 14:12:28 arpal volumio[2227]: info: Received Get System Info Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 14:12:28 arpal volumio[2227]: info: Discovery: Getting this device information Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:28 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::volumioGetState Dec 11 14:12:28 arpal volumio[2227]: info: CorePlayQueue::getTrack 0 Dec 11 14:12:28 arpal volumio[2227]: info: Listing playlists Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::volumioGetQueue Dec 11 14:12:28 arpal volumio[2227]: info: CoreStateMachine::getQueue Dec 11 14:12:28 arpal volumio[2227]: info: CorePlayQueue::getQueue Dec 11 14:12:28 arpal volumio[2227]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 11 14:12:29 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:29 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Dec 11 14:12:29 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:29 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:29 arpal go-librespot[2566]: go-librespot daemon starting... Dec 11 14:12:29 arpal go-librespot[2566]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:29 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:29 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:31 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:31 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:32 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:32 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Dec 11 14:12:32 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:32 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:32 arpal go-librespot[2568]: go-librespot daemon starting... Dec 11 14:12:32 arpal go-librespot[2568]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:32 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:32 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:34 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:34 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:35 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:35 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81. Dec 11 14:12:35 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:36 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:36 arpal go-librespot[2570]: go-librespot daemon starting... Dec 11 14:12:36 arpal go-librespot[2570]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:36 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:36 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:37 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:37 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:39 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:39 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Dec 11 14:12:39 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:39 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:39 arpal go-librespot[2573]: go-librespot daemon starting... Dec 11 14:12:39 arpal go-librespot[2573]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:39 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:39 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:40 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:40 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:42 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:42 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83. Dec 11 14:12:42 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:42 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:42 arpal go-librespot[2576]: go-librespot daemon starting... Dec 11 14:12:42 arpal go-librespot[2576]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:42 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:42 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:43 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:43 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:45 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:45 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84. Dec 11 14:12:45 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:45 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:45 arpal go-librespot[2616]: go-librespot daemon starting... Dec 11 14:12:45 arpal go-librespot[2616]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:45 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:45 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:46 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:46 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:48 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:48 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 85. Dec 11 14:12:48 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:49 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:49 arpal go-librespot[2618]: go-librespot daemon starting... Dec 11 14:12:49 arpal go-librespot[2618]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:49 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:49 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:49 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:49 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:52 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:52 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 86. Dec 11 14:12:52 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:52 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:52 arpal go-librespot[2620]: go-librespot daemon starting... Dec 11 14:12:52 arpal go-librespot[2620]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:52 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:52 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:52 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:52 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:55 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:55 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:55 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:55 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 87. Dec 11 14:12:55 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:55 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:55 arpal go-librespot[2622]: go-librespot daemon starting... Dec 11 14:12:55 arpal go-librespot[2622]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:55 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:55 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:12:58 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:12:58 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:12:58 arpal systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 14:12:58 arpal systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 88. Dec 11 14:12:58 arpal systemd[1]: Stopped go-librespot Daemon. Dec 11 14:12:58 arpal systemd[1]: Started go-librespot Daemon. Dec 11 14:12:58 arpal go-librespot[2625]: go-librespot daemon starting... Dec 11 14:12:58 arpal go-librespot[2625]: /bin/start-go-librespot.sh: 7: /bin/start-go-librespot.sh: /usr/bin/go-librespot: not found Dec 11 14:12:58 arpal systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Dec 11 14:12:58 arpal systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 14:13:01 arpal volumio[2227]: info: Initializing connection to go-librespot Websocket Dec 11 14:13:01 arpal volumio[2227]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 11 14:13:01 arpal volumio[2227]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 14:13:01 arpal volumio[2227]: Error: read ECONNRESET Dec 11 14:13:01 arpal volumio[2227]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Dec 11 14:13:01 arpal volumio[2227]: errno: -104, Dec 11 14:13:01 arpal volumio[2227]: code: 'ECONNRESET', Dec 11 14:13:01 arpal volumio[2227]: syscall: 'read' Dec 11 14:13:01 arpal volumio[2227]: } Dec 11 14:13:01 arpal volumio[2227]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 14:13:01 arpal sudo[2636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-11 14:12 Dec 11 14:13:01 arpal sudo[2636]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"