-- Logs begin at Thu 2025-05-15 20:09:39 CEST, end at Thu 2025-05-15 21:55:56 CEST. -- May 15 21:54:00 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:00 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1898. May 15 21:54:00 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:00 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:00 volumio02 go-librespot[22573]: Librespot-go daemon starting... May 15 21:54:00 volumio02 go-librespot[22573]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:00 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:00 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:00 volumio02 sudo[22574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 21:54:00 volumio02 sudo[22574]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 21:54:00 volumio02 sudo[22580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 21:54:00 volumio02 sudo[22580]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 21:54:00 volumio02 sudo[22574]: pam_unix(sudo:session): session closed for user root May 15 21:54:00 volumio02 sudo[22580]: pam_unix(sudo:session): session closed for user root May 15 21:54:00 volumio02 volumio[1179]: verbose: New Socket.io Connection to 10.0.1.146 from 10.0.1.8 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 21:54:01 volumio02 sudo[22585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 15 21:54:01 volumio02 sudo[22585]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 21:54:01 volumio02 sudo[22585]: pam_unix(sudo:session): session closed for user root May 15 21:54:01 volumio02 sudo[22589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 15 21:54:01 volumio02 sudo[22589]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 15 21:54:01 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:01 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:01 volumio02 sudo[22589]: pam_unix(sudo:session): session closed for user root May 15 21:54:01 volumio02 volumio[1179]: verbose: New Socket.io Connection to 10.0.1.146 from 10.0.1.8 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::volumioGetVisibleSources May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::volumioGetState May 15 21:54:01 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::volumioGetQueue May 15 21:54:01 volumio02 volumio[1179]: info: CoreStateMachine::getQueue May 15 21:54:01 volumio02 volumio[1179]: info: CorePlayQueue::getQueue May 15 21:54:01 volumio02 volumio[1179]: info: Listing playlists May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 15 21:54:01 volumio02 volumio[1179]: info: Received Get System Info May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 21:54:01 volumio02 volumio[1179]: info: Discovery: Getting this device information May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::volumioGetState May 15 21:54:01 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::volumioGetState May 15 21:54:01 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 21:54:01 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 15 21:54:02 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 21:54:02 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 21:54:02 volumio02 volumio[1179]: info: Discovery: Getting this device information May 15 21:54:02 volumio02 volumio[1179]: info: CoreCommandRouter::volumioGetState May 15 21:54:02 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:02 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 21:54:02 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 15 21:54:03 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 21:54:03 volumio02 volumio[1179]: info: Received Get System Info May 15 21:54:03 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 21:54:03 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 21:54:03 volumio02 volumio[1179]: info: Discovery: Getting this device information May 15 21:54:03 volumio02 volumio[1179]: info: CoreCommandRouter::volumioGetState May 15 21:54:03 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:03 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 21:54:03 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:03 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1899. May 15 21:54:03 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:04 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:04 volumio02 go-librespot[22592]: Librespot-go daemon starting... May 15 21:54:04 volumio02 go-librespot[22592]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:04 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:04 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:04 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:04 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:04 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 15 21:54:04 volumio02 volumio[1179]: info: Received Get System Info May 15 21:54:04 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 21:54:04 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 21:54:04 volumio02 volumio[1179]: info: Discovery: Getting this device information May 15 21:54:04 volumio02 volumio[1179]: info: CoreCommandRouter::volumioGetState May 15 21:54:04 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:04 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 21:54:06 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: nowyswiat , handleBrowseUri May 15 21:54:06 volumio02 volumio[1179]: info: [1747338846597] [RadioNowySwiat] handleBrowseUri curUri: nowyswiat May 15 21:54:06 volumio02 volumio[1179]: info: [1747338846597] [RadioNowySwiat] getRadioContent url: nowyswiat May 15 21:54:06 volumio02 volumio[1179]: info: Preload queue cleared May 15 21:54:07 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:07 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1900. May 15 21:54:07 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:07 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:07 volumio02 go-librespot[22611]: Librespot-go daemon starting... May 15 21:54:07 volumio02 go-librespot[22611]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:07 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:07 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:07 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:07 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:07 volumio02 volumio[1179]: info: Preload queue cleared May 15 21:54:07 volumio02 volumio[1179]: info: CoreCommandRouter::volumioReplaceandPlayItems May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::ClearQueue May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::stop May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 21:54:07 volumio02 volumio[1179]: info: CorePlayQueue::clearPlayQueue May 15 21:54:07 volumio02 volumio[1179]: info: CorePlayQueue::saveQueue May 15 21:54:07 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushQueue May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::addQueueItems May 15 21:54:07 volumio02 volumio[1179]: info: CorePlayQueue::addQueueItems May 15 21:54:07 volumio02 volumio[1179]: info: Preload queue cleared May 15 21:54:07 volumio02 volumio[1179]: info: Adding Item to queue: webrns/1 May 15 21:54:07 volumio02 volumio[1179]: info: Exploding uri webrns/1 in service nowyswiat May 15 21:54:07 volumio02 volumio[1179]: info: [ ================== >>>> ] [RADIONOWYSWIAT] explodeUri: webrns/1 May 15 21:54:07 volumio02 volumio[1179]: info: [ ================== >>>> ] [RADIONOWYSWIAT] channel: 1 May 15 21:54:07 volumio02 volumio[1179]: info: [ ================== >>>> ] [RADIONOWYSWIAT] RNS_Link: http://stream.rcs.revma.com/ypqt40u0x1zuv May 15 21:54:07 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushQueue May 15 21:54:07 volumio02 volumio[1179]: info: CorePlayQueue::saveQueue May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::updateTrackBlock May 15 21:54:07 volumio02 volumio[1179]: info: CorePlayQueue::getTrackBlock May 15 21:54:07 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPlay May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::play index 0 May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::stop May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::play index undefined May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 21:54:07 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::startPlaybackTimer May 15 21:54:07 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:07 volumio02 volumio[1179]: info: [ ================== >>>> ] [RADIONOWYSWIAT] Stream 256Kb: http://stream.rcs.revma.com/ypqt40u0x1zuv May 15 21:54:07 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand stop May 15 21:54:07 volumio02 volumio[1179]: info: sendMpdCommand stop took 20 milliseconds May 15 21:54:07 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand clear May 15 21:54:07 volumio02 volumio[1179]: info: May 15 21:54:07 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:54:07 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:54:07 volumio02 volumio[1179]: info: sendMpdCommand clear took 4 milliseconds May 15 21:54:07 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ypqt40u0x1zuv" May 15 21:54:07 volumio02 volumio[1179]: info: May 15 21:54:07 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:54:07 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:54:07 volumio02 volumio[1179]: info: May 15 21:54:07 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:54:07 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:54:07 volumio02 volumio[1179]: error: updateQueue error: null May 15 21:54:07 volumio02 volumio[1179]: info: May 15 21:54:07 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:54:07 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:54:07 volumio02 volumio[1179]: info: ------------------------------ 13ms May 15 21:54:07 volumio02 volumio[1179]: info: sendMpdCommand add "http://stream.rcs.revma.com/ypqt40u0x1zuv" took 18 milliseconds May 15 21:54:07 volumio02 volumio[1179]: info: ------------------------------ 17ms May 15 21:54:07 volumio02 volumio[1179]: info: ------------------------------ 15ms May 15 21:54:07 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand play May 15 21:54:07 volumio02 volumio[1179]: info: May 15 21:54:07 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:54:07 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:54:07 volumio02 volumio[1179]: info: May 15 21:54:07 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:54:07 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:54:07 volumio02 volumio[1179]: error: Upnp client error: Error: This socket has been ended by the other party May 15 21:54:07 volumio02 volumio[1179]: info: ------------------------------ 47ms May 15 21:54:07 volumio02 volumio[1179]: info: sendMpdCommand play took 17 milliseconds May 15 21:54:07 volumio02 volumio[1179]: info: ------------------------------ 16ms May 15 21:54:07 volumio02 volumio[1179]: info: ------------------------------ 9ms May 15 21:54:07 volumio02 volumio[1179]: info: CoreStateMachine::setConsumeUpdateService mpd May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand status took 9 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand status took 7 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand status took 11 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand status took 5 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 3 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 4 milliseconds May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ypqt40u0x1zuv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:54:10 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus stop May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ypqt40u0x1zuv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:54:10 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:54:10 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 99ms May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 132ms May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:54:10 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:54:10 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 132 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 131 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand status took 9 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand status took 9 milliseconds May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ypqt40u0x1zuv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:54:10 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:54:10 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ypqt40u0x1zuv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:54:10 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:54:10 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 290ms May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 285ms May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:54:10 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:54:10 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:54:10 volumio02 volumio[1179]: info: May 15 21:54:10 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:54:10 volumio02 volumio[1179]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Radio Nowy Swiat (MP3 256k) May 15 21:54:10 volumio02 volumio[1179]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Radio Nowy Swiat (MP3 256k) May 15 21:54:10 volumio02 volumio[1179]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Radio Nowy Swiat (MP3 256k) May 15 21:54:10 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 250ms May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand status took 247 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 249ms May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand status took 248 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 241 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 240 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 104ms May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand status took 100 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 99ms May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand status took 98 milliseconds May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chico Pinheiro & Romero Lubambo - Windmills of Your Mind","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:54:10 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:54:10 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chico Pinheiro & Romero Lubambo - Windmills of Your Mind","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:54:10 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:54:10 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1901. May 15 21:54:10 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:10 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:10 volumio02 go-librespot[22627]: Librespot-go daemon starting... May 15 21:54:10 volumio02 go-librespot[22627]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:10 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:10 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 374ms May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 374ms May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 118 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 117 milliseconds May 15 21:54:10 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 117 milliseconds May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:54:10 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chico Pinheiro & Romero Lubambo - Windmills of Your Mind","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:54:10 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:54:10 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chico Pinheiro & Romero Lubambo - Windmills of Your Mind","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:54:10 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:54:10 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chico Pinheiro & Romero Lubambo - Windmills of Your Mind","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:54:10 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:54:10 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:54:10 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:54:10 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chico Pinheiro & Romero Lubambo - Windmills of Your Mind","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:54:10 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:54:10 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:54:10 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 617ms May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 617ms May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 467ms May 15 21:54:10 volumio02 volumio[1179]: info: ------------------------------ 466ms May 15 21:54:10 volumio02 volumio[1179]: info: [LastFM] Split composite title into artist: Chico Pinheiro & Romero Lubambo and title: Windmills of Your Mind May 15 21:54:10 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:11 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 15 21:54:11 volumio02 volumio[1179]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Windmills of Your Mind","url":"https://www.last.fm/music/Chico+Pinheiro+&+Romero+Lubambo/_/Windmills+of+Your+Mind","duration":"221000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"5","playcount":"10","artist":{"name":"Chico Pinheiro & Romero Lubambo","url":"https://www.last.fm/music/Chico+Pinheiro+&+Romero+Lubambo"},"album":{"artist":"Chico Pinheiro & Romero Lubambo","title":"Two Brothers","url":"https://www.last.fm/music/Chico+Pinheiro+&+Romero+Lubambo/Two+Brothers","image":[{"@":{"size":"small"}},{"@":{"size":"medium"}},{"@":{"size":"large"}},{"@":{"size":"extralarge"}}]},"userplaycount":"0","userloved":"0","toptags":""}} May 15 21:54:11 volumio02 volumio[1179]: info: [LastFM] Updated missing track duration: 221000 May 15 21:54:11 volumio02 volumio[1179]: info: [LastFM] Updated missing track album: Two Brothers May 15 21:54:13 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:13 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1902. May 15 21:54:13 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:13 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:13 volumio02 go-librespot[22639]: Librespot-go daemon starting... May 15 21:54:13 volumio02 go-librespot[22639]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:13 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:13 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:13 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:13 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:16 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:16 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1903. May 15 21:54:16 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:16 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:16 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:17 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:17 volumio02 go-librespot[22696]: Librespot-go daemon starting... May 15 21:54:17 volumio02 go-librespot[22696]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:17 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:17 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:19 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:19 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:20 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:20 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1904. May 15 21:54:20 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:20 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:20 volumio02 go-librespot[22726]: Librespot-go daemon starting... May 15 21:54:20 volumio02 go-librespot[22726]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:20 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:20 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:22 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:23 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:23 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:23 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1905. May 15 21:54:23 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:23 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:23 volumio02 go-librespot[22736]: Librespot-go daemon starting... May 15 21:54:23 volumio02 go-librespot[22736]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:23 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:23 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:26 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:26 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:26 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:26 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1906. May 15 21:54:26 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:26 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:26 volumio02 go-librespot[22758]: Librespot-go daemon starting... May 15 21:54:26 volumio02 go-librespot[22758]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:26 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:26 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:29 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:29 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:29 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:29 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1907. May 15 21:54:29 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:30 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:30 volumio02 go-librespot[22769]: Librespot-go daemon starting... May 15 21:54:30 volumio02 go-librespot[22769]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:30 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:30 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:32 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:32 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:33 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:33 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1908. May 15 21:54:33 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:33 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:33 volumio02 go-librespot[22778]: Librespot-go daemon starting... May 15 21:54:33 volumio02 go-librespot[22778]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:33 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:33 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:35 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:35 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:36 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:36 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1909. May 15 21:54:36 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:36 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:36 volumio02 go-librespot[22800]: Librespot-go daemon starting... May 15 21:54:36 volumio02 go-librespot[22800]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:36 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:36 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:38 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:38 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:39 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:39 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1910. May 15 21:54:39 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:39 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:39 volumio02 go-librespot[22811]: Librespot-go daemon starting... May 15 21:54:39 volumio02 go-librespot[22811]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:39 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:39 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:41 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:41 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:42 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:42 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1911. May 15 21:54:42 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:42 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:43 volumio02 go-librespot[22821]: Librespot-go daemon starting... May 15 21:54:43 volumio02 go-librespot[22821]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:43 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:43 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:44 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:44 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:46 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:46 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1912. May 15 21:54:46 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:46 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:46 volumio02 go-librespot[22843]: Librespot-go daemon starting... May 15 21:54:46 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:46 volumio02 go-librespot[22843]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:46 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:47 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:47 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:49 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:49 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1913. May 15 21:54:49 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:49 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:49 volumio02 go-librespot[22854]: Librespot-go daemon starting... May 15 21:54:49 volumio02 go-librespot[22854]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:49 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:49 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:50 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:50 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:52 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:52 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1914. May 15 21:54:52 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:52 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:52 volumio02 go-librespot[22863]: Librespot-go daemon starting... May 15 21:54:52 volumio02 go-librespot[22863]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:52 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:52 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:53 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:53 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:55 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:55 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1915. May 15 21:54:55 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:56 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:56 volumio02 go-librespot[22871]: Librespot-go daemon starting... May 15 21:54:56 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:56 volumio02 go-librespot[22871]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:56 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:54:56 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:56 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:59 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:54:59 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:54:59 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:54:59 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1916. May 15 21:54:59 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:54:59 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:54:59 volumio02 go-librespot[22897]: Librespot-go daemon starting... May 15 21:54:59 volumio02 go-librespot[22897]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:54:59 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:54:59 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:02 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:02 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:02 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:02 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1917. May 15 21:55:02 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:02 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:02 volumio02 go-librespot[22907]: Librespot-go daemon starting... May 15 21:55:02 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:02 volumio02 go-librespot[22907]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:02 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:05 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:05 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:05 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:05 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1918. May 15 21:55:05 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:05 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:05 volumio02 go-librespot[22915]: Librespot-go daemon starting... May 15 21:55:05 volumio02 go-librespot[22915]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:05 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:05 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:08 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:08 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:08 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:08 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1919. May 15 21:55:08 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:08 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:09 volumio02 go-librespot[22940]: Librespot-go daemon starting... May 15 21:55:09 volumio02 go-librespot[22940]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:09 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:09 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:11 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:11 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:12 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:12 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1920. May 15 21:55:12 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:12 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:12 volumio02 go-librespot[22949]: Librespot-go daemon starting... May 15 21:55:12 volumio02 go-librespot[22949]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:12 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:12 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:14 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:14 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:15 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:15 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1921. May 15 21:55:15 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:15 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:15 volumio02 go-librespot[22958]: Librespot-go daemon starting... May 15 21:55:15 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:15 volumio02 go-librespot[22958]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:15 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:17 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:17 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:18 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:18 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1922. May 15 21:55:18 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:18 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:18 volumio02 go-librespot[23037]: Librespot-go daemon starting... May 15 21:55:18 volumio02 go-librespot[23037]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:18 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:18 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:20 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:20 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:21 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:21 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1923. May 15 21:55:21 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:22 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:22 volumio02 go-librespot[23048]: Librespot-go daemon starting... May 15 21:55:22 volumio02 go-librespot[23048]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:22 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:22 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:23 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:23 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:25 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:25 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1924. May 15 21:55:25 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:25 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:25 volumio02 go-librespot[23057]: Librespot-go daemon starting... May 15 21:55:25 volumio02 go-librespot[23057]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:25 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:25 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:26 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:26 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:28 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:28 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1925. May 15 21:55:28 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:28 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:28 volumio02 go-librespot[23082]: Librespot-go daemon starting... May 15 21:55:28 volumio02 go-librespot[23082]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:28 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:28 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:29 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:29 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:31 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:31 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1926. May 15 21:55:31 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:31 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:31 volumio02 go-librespot[23090]: Librespot-go daemon starting... May 15 21:55:31 volumio02 go-librespot[23090]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:31 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:31 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:32 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:32 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:34 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:34 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1927. May 15 21:55:34 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:35 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:35 volumio02 go-librespot[23098]: Librespot-go daemon starting... May 15 21:55:35 volumio02 go-librespot[23098]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:35 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:35 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:35 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:35 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:36 volumio02 volumio[1179]: info: May 15 21:55:36 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:55:36 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:55:36 volumio02 volumio[1179]: info: May 15 21:55:36 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:55:36 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:55:36 volumio02 volumio[1179]: info: May 15 21:55:36 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:55:36 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:55:36 volumio02 volumio[1179]: info: May 15 21:55:36 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:55:36 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:55:36 volumio02 volumio[1179]: info: May 15 21:55:36 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:55:36 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:55:36 volumio02 volumio[1179]: info: May 15 21:55:36 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:55:36 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:55:36 volumio02 volumio[1179]: info: ------------------------------ 8ms May 15 21:55:36 volumio02 volumio[1179]: info: sendMpdCommand status took 8 milliseconds May 15 21:55:36 volumio02 volumio[1179]: info: ------------------------------ 7ms May 15 21:55:36 volumio02 volumio[1179]: info: sendMpdCommand status took 6 milliseconds May 15 21:55:36 volumio02 volumio[1179]: info: ------------------------------ 5ms May 15 21:55:36 volumio02 volumio[1179]: info: sendMpdCommand status took 5 milliseconds May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:55:36 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 3 milliseconds May 15 21:55:36 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 3 milliseconds May 15 21:55:36 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 3 milliseconds May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:55:36 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:55:36 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:55:36 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:55:36 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":87220,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Nowy Świat - Pion i poziom!","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:55:36 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:55:36 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 21:55:36 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:36 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:55:36 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:55:36 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":87220,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Nowy Świat - Pion i poziom!","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:55:36 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:55:36 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:36 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:55:36 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:55:36 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":87220,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio Nowy Świat - Pion i poziom!","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:55:36 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:55:36 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:36 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:36 volumio02 volumio[1179]: info: ------------------------------ 98ms May 15 21:55:36 volumio02 volumio[1179]: info: ------------------------------ 96ms May 15 21:55:36 volumio02 volumio[1179]: info: ------------------------------ 95ms May 15 21:55:36 volumio02 volumio[1179]: info: [LastFM] Split composite title into artist: Radio Nowy Świat and title: Pion i poziom! May 15 21:55:36 volumio02 volumio[1179]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Pion i poziom!","url":"https://www.last.fm/music/Radio+Nowy+%C5%9Awiat/_/Pion+i+poziom%21","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"403","playcount":"263269","artist":{"name":"Radio Nowy Świat","url":"https://www.last.fm/music/Radio+Nowy+%C5%9Awiat"},"userplaycount":"518","userloved":"0","toptags":""}} May 15 21:55:36 volumio02 volumio[1179]: info: [LastFM] Updated missing track duration: 0 May 15 21:55:38 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:38 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:38 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:38 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1928. May 15 21:55:38 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:38 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:38 volumio02 go-librespot[23128]: Librespot-go daemon starting... May 15 21:55:38 volumio02 go-librespot[23128]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:38 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:38 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:41 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:41 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:41 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:41 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1929. May 15 21:55:41 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:41 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:41 volumio02 go-librespot[23138]: Librespot-go daemon starting... May 15 21:55:41 volumio02 go-librespot[23138]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:41 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:41 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:44 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:44 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:44 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:44 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1930. May 15 21:55:44 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:44 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:44 volumio02 go-librespot[23146]: Librespot-go daemon starting... May 15 21:55:44 volumio02 go-librespot[23146]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:44 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:44 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:47 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:47 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:47 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:47 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1931. May 15 21:55:47 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:47 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:48 volumio02 go-librespot[23171]: Librespot-go daemon starting... May 15 21:55:48 volumio02 go-librespot[23171]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:48 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:48 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:50 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:50 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:51 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:51 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1932. May 15 21:55:51 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:51 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:51 volumio02 go-librespot[23179]: Librespot-go daemon starting... May 15 21:55:51 volumio02 go-librespot[23179]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:51 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:51 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:52 volumio02 volumio[1179]: info: May 15 21:55:52 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:55:52 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:55:52 volumio02 volumio[1179]: info: May 15 21:55:52 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:55:52 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:55:52 volumio02 volumio[1179]: info: May 15 21:55:52 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:55:52 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:55:52 volumio02 volumio[1179]: info: May 15 21:55:52 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:55:52 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:55:52 volumio02 volumio[1179]: info: May 15 21:55:52 volumio02 volumio[1179]: ---------------------------- MPD announces system playlist update May 15 21:55:52 volumio02 volumio[1179]: info: Ignoring MPD Status Update May 15 21:55:52 volumio02 volumio[1179]: info: May 15 21:55:52 volumio02 volumio[1179]: ---------------------------- MPD announces state update: player May 15 21:55:52 volumio02 volumio[1179]: info: ControllerMpd::getState May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand status May 15 21:55:52 volumio02 volumio[1179]: info: ------------------------------ 11ms May 15 21:55:52 volumio02 volumio[1179]: info: sendMpdCommand status took 7 milliseconds May 15 21:55:52 volumio02 volumio[1179]: info: ------------------------------ 7ms May 15 21:55:52 volumio02 volumio[1179]: info: sendMpdCommand status took 6 milliseconds May 15 21:55:52 volumio02 volumio[1179]: info: ------------------------------ 5ms May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:55:52 volumio02 volumio[1179]: info: sendMpdCommand status took 6 milliseconds May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::parseState May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 15 21:55:52 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 4 milliseconds May 15 21:55:52 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 3 milliseconds May 15 21:55:52 volumio02 volumio[1179]: info: sendMpdCommand playlistinfo took 6 milliseconds May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:55:52 volumio02 volumio[1179]: verbose: ControllerMpd::parseTrackInfo May 15 21:55:52 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:55:52 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:55:52 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":103223,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chico Pinheiro & Romero Lubambo - For No One","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:55:52 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:55:52 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 15 21:55:52 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:52 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:55:52 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:55:52 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":103223,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chico Pinheiro & Romero Lubambo - For No One","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:55:52 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:55:52 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:52 volumio02 volumio[1179]: info: ControllerMpd::pushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreCommandRouter::servicePushState May 15 21:55:52 volumio02 volumio[1179]: info: CorePlayQueue::getTrack 0 May 15 21:55:52 volumio02 volumio[1179]: verbose: STATE SERVICE {"status":"play","position":0,"seek":103223,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chico Pinheiro & Romero Lubambo - For No One","artist":"Radio Nowy Swiat","album":null,"uri":"http://stream.rcs.revma.com/ypqt40u0x1zuv","trackType":""} May 15 21:55:52 volumio02 volumio[1179]: verbose: CURRENT POSITION 0 May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::syncState stateService play May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::syncState currentStatus play May 15 21:55:52 volumio02 volumio[1179]: info: Received an update from plugin. extracting info from payload May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreStateMachine::pushState May 15 21:55:52 volumio02 volumio[1179]: info: CoreCommandRouter::volumioPushState May 15 21:55:52 volumio02 volumio[1179]: info: ------------------------------ 99ms May 15 21:55:52 volumio02 volumio[1179]: info: ------------------------------ 97ms May 15 21:55:52 volumio02 volumio[1179]: info: ------------------------------ 96ms May 15 21:55:52 volumio02 volumio[1179]: info: [LastFM] Split composite title into artist: Chico Pinheiro & Romero Lubambo and title: For No One May 15 21:55:53 volumio02 volumio[1179]: info: Initializing connection to go-librespot Websocket May 15 21:55:53 volumio02 volumio[1179]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 May 15 21:55:54 volumio02 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. May 15 21:55:54 volumio02 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1933. May 15 21:55:54 volumio02 systemd[1]: Stopped go-librespot Daemon. May 15 21:55:54 volumio02 systemd[1]: Started go-librespot Daemon. May 15 21:55:54 volumio02 go-librespot[23189]: Librespot-go daemon starting... May 15 21:55:54 volumio02 go-librespot[23189]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found May 15 21:55:54 volumio02 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a May 15 21:55:54 volumio02 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 15 21:55:55 volumio02 volumio[1179]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 21:55:55 volumio02 volumio[1179]: TypeError: Cannot read property '@' of null May 15 21:55:55 volumio02 volumio[1179]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) May 15 21:55:55 volumio02 volumio[1179]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 May 15 21:55:55 volumio02 volumio[1179]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:304:18) May 15 21:55:55 volumio02 volumio[1179]: at Parser.emit (events.js:400:28) May 15 21:55:55 volumio02 volumio[1179]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:314:16) May 15 21:55:55 volumio02 volumio[1179]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) May 15 21:55:55 volumio02 volumio[1179]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) May 15 21:55:55 volumio02 volumio[1179]: at IncomingMessage.emit (events.js:412:35) May 15 21:55:55 volumio02 volumio[1179]: at endReadableNT (internal/streams/readable.js:1333:12) May 15 21:55:55 volumio02 volumio[1179]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 15 21:55:55 volumio02 volumio[1179]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 21:55:56 volumio02 sudo[23204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-15 21:54 May 15 21:55:56 volumio02 sudo[23204]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"