Mar 28 13:57:00 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:00 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1260. Mar 28 13:57:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:01 volumio volumio[1047]: info: Spotify config file written Mar 28 13:57:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:01 volumio go-librespot[14188]: go-librespot daemon starting... Mar 28 13:57:01 volumio go-librespot[14188]: Segmentation fault Mar 28 13:57:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:01 volumio sudo[14190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 28 13:57:01 volumio sudo[14190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 13:57:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:01 volumio go-librespot[14193]: go-librespot daemon starting... Mar 28 13:57:01 volumio go-librespot[14193]: Segmentation fault Mar 28 13:57:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:01 volumio sudo[14190]: pam_unix(sudo:session): session closed for user root Mar 28 13:57:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 13:57:02 volumio volumio[1047]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 13:57:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 13:57:02 volumio volumio[1047]: info: Received Get System Version Mar 28 13:57:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 13:57:02 volumio volumio[1047]: info: Received Get System Info Mar 28 13:57:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 13:57:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 13:57:02 volumio volumio[1047]: info: Discovery: Getting this device information Mar 28 13:57:02 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Mar 28 13:57:02 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:02 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 13:57:03 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:03 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:04 volumio volumio[1047]: info: go-librespot daemon successfully initialized Mar 28 13:57:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1261. Mar 28 13:57:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:04 volumio go-librespot[14209]: go-librespot daemon starting... Mar 28 13:57:04 volumio go-librespot[14209]: Segmentation fault Mar 28 13:57:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:04 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:04.932Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.70.247:45858 @ 0x2c00b70" latency=591.516294ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 28 13:57:05 volumio sudo[14212]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 13:57:05 volumio sudo[14212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 13:57:05 volumio sudo[14214]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 13:57:05 volumio sudo[14212]: pam_unix(sudo:session): session closed for user root Mar 28 13:57:05 volumio sudo[14214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 13:57:05 volumio sudo[14214]: pam_unix(sudo:session): session closed for user root Mar 28 13:57:05 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.70.241 from 192.168.70.247 UA: Mozilla/5.0 (Linux; Android 15; motorola edge 60 pro Build/V2VVS35.58-93-3-1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 28 13:57:06 volumio sudo[14220]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 13:57:06 volumio sudo[14220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 13:57:06 volumio sudo[14220]: pam_unix(sudo:session): session closed for user root Mar 28 13:57:06 volumio sudo[14222]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 13:57:06 volumio sudo[14222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 13:57:06 volumio sudo[14222]: pam_unix(sudo:session): session closed for user root Mar 28 13:57:06 volumio volumio[1047]: verbose: New Socket.io Connection to 192.168.70.241 from 192.168.70.247 UA: Mozilla/5.0 (Linux; Android 15; motorola edge 60 pro Build/V2VVS35.58-93-3-1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 28 13:57:06 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:06 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Mar 28 13:57:06 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 13:57:06 volumio volumio[1047]: info: Received Get System Info Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 13:57:06 volumio volumio[1047]: info: Discovery: Getting this device information Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Mar 28 13:57:06 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Mar 28 13:57:06 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:06 volumio volumio[1047]: info: Listing playlists Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 13:57:06 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 13:57:07 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:07 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 13:57:07 volumio volumio[1047]: info: Received Get System Info Mar 28 13:57:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 13:57:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 13:57:07 volumio volumio[1047]: info: Discovery: Getting this device information Mar 28 13:57:07 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Mar 28 13:57:07 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 13:57:07 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 13:57:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1262. Mar 28 13:57:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:08 volumio go-librespot[14228]: go-librespot daemon starting... Mar 28 13:57:08 volumio go-librespot[14228]: Segmentation fault Mar 28 13:57:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:08 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 13:57:08 volumio volumio[1047]: info: Received Get System Info Mar 28 13:57:08 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 13:57:08 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 13:57:08 volumio volumio[1047]: info: Discovery: Getting this device information Mar 28 13:57:08 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Mar 28 13:57:08 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:08 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 13:57:10 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:10 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1263. Mar 28 13:57:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:11 volumio go-librespot[14230]: go-librespot daemon starting... Mar 28 13:57:11 volumio go-librespot[14230]: Segmentation fault Mar 28 13:57:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:13 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:13 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1264. Mar 28 13:57:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:14 volumio go-librespot[14232]: go-librespot daemon starting... Mar 28 13:57:14 volumio go-librespot[14232]: Segmentation fault Mar 28 13:57:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:16 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 28 13:57:16 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:16 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1265. Mar 28 13:57:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:17 volumio go-librespot[14248]: go-librespot daemon starting... Mar 28 13:57:17 volumio go-librespot[14248]: Segmentation fault Mar 28 13:57:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:19 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:19 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1266. Mar 28 13:57:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:21 volumio go-librespot[14251]: go-librespot daemon starting... Mar 28 13:57:21 volumio go-librespot[14251]: Segmentation fault Mar 28 13:57:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:22 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:22 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::volumioNext Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::next Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::stop Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::pushState Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::serviceStop Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::serviceStop Mar 28 13:57:24 volumio volumio[1047]: info: ControllerMpd::stop Mar 28 13:57:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.161Z level=INFO msg="emitting player state changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.167Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" id=https://stream.radioparadise.com/mellow-flacm title="You Wear it Well" Mar 28 13:57:24 volumio volumio[1047]: info: Mar 28 13:57:24 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:57:24 volumio volumio[1047]: info: sendMpdCommand stop took 64 milliseconds Mar 28 13:57:24 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:57:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::pushState Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Mar 28 13:57:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1267. Mar 28 13:57:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/12340.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"You Wear it Well","title":"You Wear it Well","artist":"Rod Stewart","album":"Never A Dull Moment","streaming":true,"disableUiControls":true,"duration":247,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 28 13:57:24 volumio volumio[1047]: verbose: CURRENT POSITION 0 Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::syncState stateService stop Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::pushState Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Mar 28 13:57:24 volumio volumio[1047]: info: No code Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::pushState Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.262Z level=INFO msg="emitting player state changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" state=STATUS_STOPPED positionMs=260 volume=100 Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.263Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" id=https://stream.radioparadise.com/mellow-flacm title="You Wear it Well" Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.269Z level=INFO msg="emitting player state changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" state=STATUS_STOPPED positionMs=260 volume=100 Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.269Z level=INFO msg="emitting player state changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" state=STATUS_STOPPED positionMs=260 volume=100 Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.272Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" id=https://stream.radioparadise.com/mellow-flacm title="You Wear it Well" Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.272Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" id=https://stream.radioparadise.com/mellow-flacm title="You Wear it Well" Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::play index undefined Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Mar 28 13:57:24 volumio volumio[1047]: info: Mar 28 13:57:24 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:57:24 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:57:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:57:24 volumio volumio[1047]: info: sendMpdCommand status took 82 milliseconds Mar 28 13:57:24 volumio volumio[1047]: info: sendMpdCommand status took 8 milliseconds Mar 28 13:57:24 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:57:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:57:24 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:57:24 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:57:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 13:57:24 volumio volumio[1047]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 28 13:57:24 volumio go-librespot[14253]: go-librespot daemon starting... Mar 28 13:57:24 volumio go-librespot[14253]: Segmentation fault Mar 28 13:57:24 volumio volumio[1047]: info: Received Get System Version Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 13:57:24 volumio volumio[1047]: info: Received Get System Info Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 13:57:24 volumio volumio[1047]: info: Discovery: Getting this device information Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::volumioGetState Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 13:57:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:24 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 55 milliseconds Mar 28 13:57:24 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 56 milliseconds Mar 28 13:57:24 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:57:24 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:57:24 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::pushState Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 28 13:57:24 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:24 volumio volumio[1047]: info: CoreStateMachine::pushState Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Mar 28 13:57:24 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:24 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.386Z level=INFO msg="emitting player state changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" state=STATUS_STOPPED positionMs=260 volume=100 Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.387Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" id=https://stream.radioparadise.com/mellow-flacm title="You Wear it Well" Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.387Z level=INFO msg="emitting player state changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" state=STATUS_STOPPED positionMs=260 volume=100 Mar 28 13:57:24 volumio volumio5-onboarding[1517]: time=2026-03-28T16:57:24.389Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" id=https://stream.radioparadise.com/mellow-flacm title="You Wear it Well" Mar 28 13:57:24 volumio volumio[1047]: info: ------------------------------ 181ms Mar 28 13:57:24 volumio volumio[1047]: info: ------------------------------ 109ms Mar 28 13:57:25 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:25 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:27 volumio volumio[1047]: info: CoreCommandRouter::volumioNext Mar 28 13:57:27 volumio volumio[1047]: info: CoreStateMachine::next Mar 28 13:57:27 volumio volumio[1047]: info: CoreStateMachine::stop Mar 28 13:57:27 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:57:27 volumio volumio[1047]: info: CoreStateMachine::play index undefined Mar 28 13:57:27 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:57:27 volumio volumio[1047]: info: CorePlayQueue::getTrack 1 Mar 28 13:57:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1268. Mar 28 13:57:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:27 volumio go-librespot[14270]: go-librespot daemon starting... Mar 28 13:57:27 volumio go-librespot[14270]: Segmentation fault Mar 28 13:57:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:28 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Mar 28 13:57:28 volumio volumio[1047]: info: CoreStateMachine::play index undefined Mar 28 13:57:28 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:57:28 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:28 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:57:28 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:28 volumio volumio[1047]: info: [RadioParadise] Matched station: Radio Paradise Mellow Mix (FLAC), chan=1 Mar 28 13:57:28 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:57:28 volumio volumio[1047]: info: sendMpdCommand stop took 3 milliseconds Mar 28 13:57:28 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand clear Mar 28 13:57:28 volumio volumio[1047]: info: Mar 28 13:57:28 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Mar 28 13:57:28 volumio volumio[1047]: info: Ignoring MPD Status Update Mar 28 13:57:28 volumio volumio[1047]: info: sendMpdCommand clear took 3 milliseconds Mar 28 13:57:28 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 28 13:57:28 volumio volumio[1047]: info: Mar 28 13:57:28 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Mar 28 13:57:28 volumio volumio[1047]: info: Ignoring MPD Status Update Mar 28 13:57:28 volumio volumio[1047]: error: updateQueue error: null Mar 28 13:57:28 volumio volumio[1047]: error: updateQueue error: null Mar 28 13:57:28 volumio volumio[1047]: info: ------------------------------ 9ms Mar 28 13:57:28 volumio volumio[1047]: info: sendMpdCommand consume 1 took 7 milliseconds Mar 28 13:57:28 volumio volumio[1047]: info: ------------------------------ 5ms Mar 28 13:57:28 volumio volumio[1047]: info: [1774717048616] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flacm Mar 28 13:57:28 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flacm" Mar 28 13:57:28 volumio volumio[1047]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flacm" took 2 milliseconds Mar 28 13:57:28 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand play Mar 28 13:57:28 volumio volumio[1047]: info: Mar 28 13:57:28 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Mar 28 13:57:28 volumio volumio[1047]: info: Ignoring MPD Status Update Mar 28 13:57:28 volumio volumio[1047]: info: Mar 28 13:57:28 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Mar 28 13:57:28 volumio volumio[1047]: info: Ignoring MPD Status Update Mar 28 13:57:28 volumio volumio[1047]: info: sendMpdCommand play took 8 milliseconds Mar 28 13:57:28 volumio volumio[1047]: info: ------------------------------ 7ms Mar 28 13:57:28 volumio volumio[1047]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=1 Mar 28 13:57:28 volumio volumio[1047]: info: [1774717048635] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Mar 28 13:57:28 volumio volumio[1047]: info: ------------------------------ 30ms Mar 28 13:57:28 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:28 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:29 volumio volumio[1047]: info: [1774717049433] [RadioParadise] received new metadata: {"time":79,"artist":"Rod Stewart","title":"You Wear it Well","album":"Never A Dull Moment","year":"1972","cover":"https://img.radioparadise.com/covers/l/12340.jpg","cover_med":"https://img.radioparadise.com/covers/m/12340.jpg","cover_small":"https://img.radioparadise.com/covers/s/12340.jpg"} Mar 28 13:57:29 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:29 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:29 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:29 volumio volumio[1047]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/12340.jpg","uri":"https://stream.radioparadise.com/mellow-flacm","name":"You Wear it Well","title":"You Wear it Well","artist":"Rod Stewart","album":"Never A Dull Moment","streaming":true,"disableUiControls":true,"duration":84,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 28 13:57:29 volumio volumio[1047]: verbose: CURRENT POSITION 0 Mar 28 13:57:29 volumio volumio[1047]: info: CoreStateMachine::syncState stateService play Mar 28 13:57:29 volumio volumio[1047]: info: CoreStateMachine::syncState currentStatus stop Mar 28 13:57:29 volumio volumio[1047]: info: [1774717049437] [RadioParadise] setting new timer with duration of 84000 seconds. Mar 28 13:57:30 volumio volumio[1047]: info: Mar 28 13:57:30 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:57:30 volumio volumio[1047]: info: Mar 28 13:57:30 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:57:30 volumio volumio[1047]: info: Mar 28 13:57:30 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand status took 14 milliseconds Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand status took 12 milliseconds Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:57:30 volumio volumio[1047]: info: Mar 28 13:57:30 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand status took 7 milliseconds Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:57:30 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:30 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:30 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:57:30 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:30 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:30 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 28 13:57:30 volumio volumio[1047]: info: ------------------------------ 35ms Mar 28 13:57:30 volumio volumio[1047]: info: ------------------------------ 31ms Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:57:30 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:30 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:30 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:57:30 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:30 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:30 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 28 13:57:30 volumio volumio[1047]: info: ------------------------------ 25ms Mar 28 13:57:30 volumio volumio[1047]: info: ------------------------------ 18ms Mar 28 13:57:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1269. Mar 28 13:57:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:30 volumio go-librespot[14273]: go-librespot daemon starting... Mar 28 13:57:30 volumio go-librespot[14273]: Segmentation fault Mar 28 13:57:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:30 volumio volumio[1047]: info: Mar 28 13:57:30 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Mar 28 13:57:30 volumio volumio[1047]: info: Ignoring MPD Status Update Mar 28 13:57:30 volumio volumio[1047]: info: Mar 28 13:57:30 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:57:30 volumio volumio[1047]: info: Mar 28 13:57:30 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Mar 28 13:57:30 volumio volumio[1047]: info: Ignoring MPD Status Update Mar 28 13:57:30 volumio volumio[1047]: info: Mar 28 13:57:30 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:57:30 volumio volumio[1047]: info: ------------------------------ 13ms Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand status took 16 milliseconds Mar 28 13:57:30 volumio volumio[1047]: info: ------------------------------ 11ms Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand status took 13 milliseconds Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:57:30 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:30 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:30 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 28 13:57:30 volumio volumio[1047]: info: ------------------------------ 36ms Mar 28 13:57:30 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 28 13:57:30 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:57:30 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:57:30 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:30 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:30 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 28 13:57:30 volumio volumio[1047]: info: ------------------------------ 29ms Mar 28 13:57:31 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:31 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1270. Mar 28 13:57:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:34 volumio volumio[1047]: info: Mar 28 13:57:34 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Mar 28 13:57:34 volumio volumio[1047]: info: Ignoring MPD Status Update Mar 28 13:57:34 volumio volumio[1047]: info: Mar 28 13:57:34 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:57:34 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:57:34 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:57:34 volumio volumio[1047]: info: Mar 28 13:57:34 volumio volumio[1047]: ---------------------------- MPD announces system playlist update Mar 28 13:57:34 volumio volumio[1047]: info: Ignoring MPD Status Update Mar 28 13:57:34 volumio volumio[1047]: info: Mar 28 13:57:34 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:57:34 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:57:34 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:57:34 volumio volumio[1047]: info: ------------------------------ 10ms Mar 28 13:57:34 volumio volumio[1047]: info: sendMpdCommand status took 8 milliseconds Mar 28 13:57:34 volumio volumio[1047]: info: ------------------------------ 7ms Mar 28 13:57:34 volumio volumio[1047]: info: sendMpdCommand status took 4 milliseconds Mar 28 13:57:34 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:57:34 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:57:34 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:57:34 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:57:34 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:57:34 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 28 13:57:34 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:57:34 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:57:34 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:57:34 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:34 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:34 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 28 13:57:34 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:57:34 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:57:34 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:57:34 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 28 13:57:34 volumio volumio[1047]: info: ------------------------------ 23ms Mar 28 13:57:34 volumio volumio[1047]: info: ------------------------------ 16ms Mar 28 13:57:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:34 volumio go-librespot[14277]: go-librespot daemon starting... Mar 28 13:57:34 volumio go-librespot[14277]: Segmentation fault Mar 28 13:57:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:34 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:34 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:34 volumio volumio[1047]: info: CoreCommandRouter::volumioGetQueue Mar 28 13:57:34 volumio volumio[1047]: info: CoreStateMachine::getQueue Mar 28 13:57:34 volumio volumio[1047]: info: CorePlayQueue::getQueue Mar 28 13:57:34 volumio volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 13:57:34 volumio volumio[1047]: info: CoreCommandRouter::Close All Modals sent Mar 28 13:57:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1271. Mar 28 13:57:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:37 volumio go-librespot[14293]: go-librespot daemon starting... Mar 28 13:57:37 volumio go-librespot[14293]: Segmentation fault Mar 28 13:57:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:37 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:37 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1272. Mar 28 13:57:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:40 volumio go-librespot[14296]: go-librespot daemon starting... Mar 28 13:57:40 volumio go-librespot[14296]: Segmentation fault Mar 28 13:57:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:40 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:40 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:43 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:43 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1273. Mar 28 13:57:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:43 volumio go-librespot[14298]: go-librespot daemon starting... Mar 28 13:57:43 volumio go-librespot[14298]: Segmentation fault Mar 28 13:57:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:46 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:46 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1274. Mar 28 13:57:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:47 volumio go-librespot[14314]: go-librespot daemon starting... Mar 28 13:57:47 volumio go-librespot[14314]: Segmentation fault Mar 28 13:57:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:47 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: rp2 , handleBrowseUri Mar 28 13:57:49 volumio volumio[1047]: info: Preload queue cleared Mar 28 13:57:49 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:49 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:50 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 28 13:57:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1275. Mar 28 13:57:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:50 volumio go-librespot[14328]: go-librespot daemon starting... Mar 28 13:57:50 volumio go-librespot[14328]: Segmentation fault Mar 28 13:57:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:52 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:52 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:53 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 28 13:57:53 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 28 13:57:53 volumio systemd[1]: setdatetime-helper.service: Consumed 2.833s CPU time. Mar 28 13:57:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1276. Mar 28 13:57:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:53 volumio go-librespot[14343]: go-librespot daemon starting... Mar 28 13:57:53 volumio go-librespot[14343]: Segmentation fault Mar 28 13:57:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:55 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:55 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1277. Mar 28 13:57:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:57:56 volumio go-librespot[14359]: go-librespot daemon starting... Mar 28 13:57:56 volumio go-librespot[14359]: Segmentation fault Mar 28 13:57:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:57:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:57:58 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:57:58 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:57:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1278. Mar 28 13:57:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:58:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:58:00 volumio go-librespot[14361]: go-librespot daemon starting... Mar 28 13:58:00 volumio go-librespot[14361]: Segmentation fault Mar 28 13:58:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:58:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:58:01 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:58:01 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:58:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1279. Mar 28 13:58:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:58:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:58:03 volumio go-librespot[14365]: go-librespot daemon starting... Mar 28 13:58:03 volumio go-librespot[14365]: Segmentation fault Mar 28 13:58:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:58:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:58:03 volumio volumio[1047]: info: Preload queue cleared Mar 28 13:58:03 volumio volumio[1047]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::ClearQueue Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::stop Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::stPlaybackTimer Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::pushState Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:58:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:58:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::serviceStop Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:58:03 volumio volumio[1047]: info: CoreCommandRouter::serviceStop Mar 28 13:58:03 volumio volumio[1047]: info: ControllerMpd::stop Mar 28 13:58:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::clearPlayQueue Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::saveQueue Mar 28 13:58:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::addQueueItems Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::addQueueItems Mar 28 13:58:03 volumio volumio[1047]: info: Preload queue cleared Mar 28 13:58:03 volumio volumio[1047]: info: Adding Item to queue: rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D0%22%2C%22name%22%3A%22The%20Main%20Mix%22%2C%22title%22%3A%22The%20Main%20Mix%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F0%2Fcover_512x512%2F0.jpg%22%7D Mar 28 13:58:03 volumio volumio[1047]: info: Exploding uri rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D0%22%2C%22name%22%3A%22The%20Main%20Mix%22%2C%22title%22%3A%22The%20Main%20Mix%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F0%2Fcover_512x512%2F0.jpg%22%7D in service rp2 Mar 28 13:58:03 volumio volumio5-onboarding[1517]: time=2026-03-28T16:58:03.835Z level=INFO msg="emitting player state changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" state=STATUS_STOPPED positionMs=0 volume=100 Mar 28 13:58:03 volumio volumio5-onboarding[1517]: time=2026-03-28T16:58:03.837Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" id=https://stream.radioparadise.com/mellow-flacm title="You Wear it Well" Mar 28 13:58:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushQueue Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::saveQueue Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::updateTrackBlock Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::getTrackBlock Mar 28 13:58:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPlay Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::play index 0 Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::stop Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::play index undefined Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::startPlaybackTimer Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:58:03 volumio volumio[1047]: info: [rp2] clearAddPlayTrack: rp2/channel@id=0 Mar 28 13:58:03 volumio volumio[1047]: info: Mar 28 13:58:03 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:58:03 volumio volumio[1047]: info: sendMpdCommand stop took 134 milliseconds Mar 28 13:58:03 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:58:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:58:03 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:58:03 volumio volumio[1047]: info: CoreStateMachine::pushState Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:58:03 volumio volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 13:58:03 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Mar 28 13:58:03 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:58:03 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rp2 Received radio_paradise Mar 28 13:58:03 volumio volumio5-onboarding[1517]: time=2026-03-28T16:58:03.981Z level=INFO msg="emitting player state changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" state=STATUS_STOPPED positionMs=55 volume=100 Mar 28 13:58:03 volumio volumio5-onboarding[1517]: time=2026-03-28T16:58:03.982Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" id="rp2/channel@id=0" title="The Main Mix" Mar 28 13:58:03 volumio volumio[1047]: info: Mar 28 13:58:03 volumio volumio[1047]: ---------------------------- MPD announces state update: player Mar 28 13:58:03 volumio volumio[1047]: info: ControllerMpd::getState Mar 28 13:58:03 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand status Mar 28 13:58:04 volumio volumio[1047]: info: sendMpdCommand status took 48 milliseconds Mar 28 13:58:04 volumio volumio[1047]: info: sendMpdCommand status took 13 milliseconds Mar 28 13:58:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:58:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:58:04 volumio volumio[1047]: verbose: ControllerMpd::parseState Mar 28 13:58:04 volumio volumio[1047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 13:58:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 13:58:04 volumio volumio[1047]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 28 13:58:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:58:04 volumio volumio[1047]: verbose: ControllerMpd::parseTrackInfo Mar 28 13:58:04 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:58:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:58:04 volumio volumio[1047]: info: CoreStateMachine::pushState Mar 28 13:58:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:58:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Mar 28 13:58:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:58:04 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rp2 Received mpd Mar 28 13:58:04 volumio volumio[1047]: info: ControllerMpd::pushState Mar 28 13:58:04 volumio volumio[1047]: info: CoreCommandRouter::servicePushState Mar 28 13:58:04 volumio volumio[1047]: info: CoreStateMachine::pushState Mar 28 13:58:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:58:04 volumio volumio[1047]: info: CoreCommandRouter::volumioPushState Mar 28 13:58:04 volumio volumio[1047]: info: CorePlayQueue::getTrack 0 Mar 28 13:58:04 volumio volumio[1047]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rp2 Received mpd Mar 28 13:58:04 volumio volumio5-onboarding[1517]: time=2026-03-28T16:58:04.037Z level=INFO msg="emitting player state changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" state=STATUS_STOPPED positionMs=55 volume=100 Mar 28 13:58:04 volumio volumio5-onboarding[1517]: time=2026-03-28T16:58:04.039Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" id="rp2/channel@id=0" title="The Main Mix" Mar 28 13:58:04 volumio volumio5-onboarding[1517]: time=2026-03-28T16:58:04.040Z level=INFO msg="emitting player state changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" state=STATUS_STOPPED positionMs=55 volume=100 Mar 28 13:58:04 volumio volumio5-onboarding[1517]: time=2026-03-28T16:58:04.042Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.70.247:45858 @ 0x2c00b70" id="rp2/channel@id=0" title="The Main Mix" Mar 28 13:58:04 volumio volumio[1047]: info: ------------------------------ 100ms Mar 28 13:58:04 volumio volumio[1047]: info: ------------------------------ 67ms Mar 28 13:58:04 volumio volumio[1047]: verbose: [rp2] API: https://api.radioparadise.com/api/play?source=24&event=0&elapsed=0&bitrate=4&action=start&player_id=********&info=true&chan=0&audio_type= Mar 28 13:58:04 volumio volumio[1047]: info: Initializing connection to go-librespot Websocket Mar 28 13:58:04 volumio volumio[1047]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] Obtained block for channel "0" Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] ------------- Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] Block summary Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] ------------- Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] Stream URL: https://audio-geo.radioparadise.com/chan/0/x/1467/4/b/1467-0.flac Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] Tracks: Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] 0. Float Away (3:56 | elapsed: 0s) Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] 1. Fripp (7:17 | elapsed: 3m 56s) Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] 2. Sky Blue (6:29 | elapsed: 11m 14s) Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] 3. Releast (5:09 | elapsed: 17m 44s) Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] 4. Open the Door (w/ Hayley Williams) (3:18 | elapsed: 22m 53s) Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] 5. Que Vendra (2:55 | elapsed: 26m 11s) Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] Mar 28 13:58:05 volumio volumio[1047]: verbose: [rp2] Current track scheduled playback vs. current time: 3/28/2026, 1:56:53 PM <-> 3/28/2026, 1:58:05 PM Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] Going to start playback of current track at 1:12 Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] Starting mpv Mar 28 13:58:05 volumio volumio[1047]: /bin/sh: 1: mpv: not found Mar 28 13:58:05 volumio volumio[1047]: error: [rp2] [mpv] Failed to get mpv version: Command failed: mpv --version Mar 28 13:58:05 volumio volumio[1047]: /bin/sh: 1: mpv: not found Mar 28 13:58:05 volumio volumio[1047]: info: [rp2] [mpv] mpv process spawned Mar 28 13:58:05 volumio volumio[1047]: verbose: [rp2] Waiting for player event "playing"... Mar 28 13:58:06 volumio volumio[1047]: info: [rp2] [mpv] (PID: 14384) /bin/sh: 1: mpv: not found Mar 28 13:58:06 volumio volumio[1047]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 13:58:06 volumio volumio[1047]: TypeError: Cannot read properties of null (reading 'reset') Mar 28 13:58:06 volumio volumio[1047]: at /data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:207:48 Mar 28 13:58:06 volumio volumio[1047]: at ChildProcess. (/data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:221:19) Mar 28 13:58:06 volumio volumio[1047]: at ChildProcess.emit (node:events:514:28) Mar 28 13:58:06 volumio volumio[1047]: at maybeClose (node:internal/child_process:1105:16) Mar 28 13:58:06 volumio volumio[1047]: at Socket. (node:internal/child_process:457:11) Mar 28 13:58:06 volumio volumio[1047]: at Socket.emit (node:events:514:28) Mar 28 13:58:06 volumio volumio[1047]: at Pipe. (node:net:337:12) Mar 28 13:58:06 volumio volumio[1047]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 13:58:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1280. Mar 28 13:58:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:58:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 13:58:06 volumio go-librespot[14397]: go-librespot daemon starting... Mar 28 13:58:06 volumio go-librespot[14397]: Segmentation fault Mar 28 13:58:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Mar 28 13:58:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 13:58:08 volumio sudo[14401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 13:57' Mar 28 13:58:08 volumio sudo[14401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 13:58:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1281. Mar 28 13:58:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"