May 25 23:15:00 porch-speaker volumio[5490]: info: AutoStart - startPlayback called May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioGetQueue May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::getQueue May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getQueue May 25 23:15:00 porch-speaker volumio[5490]: info: AutoStart - Queue has 5 items May 25 23:15:00 porch-speaker volumio[5490]: info: AutoStart - Playing from position 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioPlay May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::play index 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::stop May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::play index undefined May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::startPlaybackTimer May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Gentle Ocean Surf.mp3 May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand stop May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand stop took 1 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand clear May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand clear took 0 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Gentle Ocean Surf.mp3" May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces system playlist update May 25 23:15:00 porch-speaker volumio[5490]: info: Ignoring MPD Status Update May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces system playlist update May 25 23:15:00 porch-speaker volumio[5490]: info: Ignoring MPD Status Update May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand add "INTERNAL/Gentle Ocean Surf.mp3" took 3 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand play May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces system playlist update May 25 23:15:00 porch-speaker volumio[5490]: info: Ignoring MPD Status Update May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces system playlist update May 25 23:15:00 porch-speaker volumio[5490]: info: Ignoring MPD Status Update May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 8ms May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 7ms May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand play took 6 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 5ms May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 4ms May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces system playlist update May 25 23:15:00 porch-speaker volumio[5490]: info: Ignoring MPD Status Update May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces system playlist update May 25 23:15:00 porch-speaker volumio[5490]: info: Ignoring MPD Status Update May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces state update: player May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces state update: player May 25 23:15:00 porch-speaker volumio[5490]: info: ControllerMpd::getState May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand status May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 5ms May 25 23:15:00 porch-speaker volumio[5490]: info: ControllerMpd::getState May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand status May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 4ms May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces state update: player May 25 23:15:00 porch-speaker volumio[5490]: info: ControllerMpd::getState May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand status May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces state update: player May 25 23:15:00 porch-speaker volumio[5490]: info: ControllerMpd::getState May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand status May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand status took 5 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand status took 6 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::parseState May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::parseState May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand status took 7 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand status took 6 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand playlistinfo took 3 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand playlistinfo took 3 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::parseState May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::parseState May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::parseTrackInfo May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::parseTrackInfo May 25 23:15:00 porch-speaker volumio[5490]: info: ControllerMpd::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::servicePushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":14558,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Gentle Ocean Surf.mp3","artist":null,"album":null,"uri":"INTERNAL/Gentle Ocean Surf.mp3","trackType":"mp3"} May 25 23:15:00 porch-speaker volumio[5490]: verbose: CURRENT POSITION 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::syncState stateService play May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::syncState currentStatus stop May 25 23:15:00 porch-speaker volumio[5490]: info: ControllerMpd::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::servicePushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":14558,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Gentle Ocean Surf.mp3","artist":null,"album":null,"uri":"INTERNAL/Gentle Ocean Surf.mp3","trackType":"mp3"} May 25 23:15:00 porch-speaker volumio[5490]: verbose: CURRENT POSITION 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::syncState stateService play May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::syncState currentStatus play May 25 23:15:00 porch-speaker volumio[5490]: info: Received an update from plugin. extracting info from payload May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioPushState May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioPushState May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 30ms May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.523-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" state=STATUS_PLAYING positionMs=968 volume=50 May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.523-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" id="mnt/INTERNAL/Gentle Ocean Surf.mp3" title="Gentle Ocean Surf.mp3" May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.525-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" state=STATUS_PLAYING positionMs=968 volume=50 May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.525-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" id="mnt/INTERNAL/Gentle Ocean Surf.mp3" title="Gentle Ocean Surf.mp3" May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 34ms May 25 23:15:00 porch-speaker volumio[5490]: info: May 25 23:15:00 porch-speaker volumio[5490]: ---------------------------- MPD announces state update: player May 25 23:15:00 porch-speaker volumio[5490]: info: ControllerMpd::getState May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand status May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand playlistinfo took 25 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand playlistinfo took 25 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand status took 1 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::parseTrackInfo May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::parseTrackInfo May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::parseState May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 23:15:00 porch-speaker volumio[5490]: info: ControllerMpd::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::servicePushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":14558,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"Gentle Ocean Surf.mp3","artist":null,"album":null,"uri":"INTERNAL/Gentle Ocean Surf.mp3","trackType":"mp3"} May 25 23:15:00 porch-speaker volumio[5490]: verbose: CURRENT POSITION 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::syncState stateService play May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::syncState currentStatus play May 25 23:15:00 porch-speaker volumio[5490]: info: Received an update from plugin. extracting info from payload May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioPushState May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioPushState May 25 23:15:00 porch-speaker volumio[5490]: info: ControllerMpd::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::servicePushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":14558,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"Gentle Ocean Surf.mp3","artist":null,"album":null,"uri":"INTERNAL/Gentle Ocean Surf.mp3","trackType":"mp3"} May 25 23:15:00 porch-speaker volumio[5490]: verbose: CURRENT POSITION 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::syncState stateService play May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::syncState currentStatus play May 25 23:15:00 porch-speaker volumio[5490]: info: Received an update from plugin. extracting info from payload May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioPushState May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioPushState May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.549-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" state=STATUS_PLAYING positionMs=968 volume=50 May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.550-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" id="mnt/INTERNAL/Gentle Ocean Surf.mp3" title="Gentle Ocean Surf.mp3" May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.550-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" state=STATUS_PLAYING positionMs=968 volume=50 May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.551-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" id="mnt/INTERNAL/Gentle Ocean Surf.mp3" title="Gentle Ocean Surf.mp3" May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.551-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" state=STATUS_PLAYING positionMs=968 volume=50 May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.552-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" id="mnt/INTERNAL/Gentle Ocean Surf.mp3" title="Gentle Ocean Surf.mp3" May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.553-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" state=STATUS_PLAYING positionMs=968 volume=50 May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.553-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" id="mnt/INTERNAL/Gentle Ocean Surf.mp3" title="Gentle Ocean Surf.mp3" May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 61ms May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 61ms May 25 23:15:00 porch-speaker volumio[5490]: info: sendMpdCommand playlistinfo took 25 milliseconds May 25 23:15:00 porch-speaker volumio[5490]: verbose: ControllerMpd::parseTrackInfo May 25 23:15:00 porch-speaker volumio[5490]: info: ControllerMpd::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::servicePushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":14558,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"Gentle Ocean Surf.mp3","artist":null,"album":null,"uri":"INTERNAL/Gentle Ocean Surf.mp3","trackType":"mp3"} May 25 23:15:00 porch-speaker volumio[5490]: verbose: CURRENT POSITION 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::syncState stateService play May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::syncState currentStatus play May 25 23:15:00 porch-speaker volumio[5490]: info: Received an update from plugin. extracting info from payload May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioPushState May 25 23:15:00 porch-speaker volumio[5490]: info: CoreStateMachine::pushState May 25 23:15:00 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:00 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioPushState May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.567-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" state=STATUS_PLAYING positionMs=968 volume=50 May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.567-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" id="mnt/INTERNAL/Gentle Ocean Surf.mp3" title="Gentle Ocean Surf.mp3" May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.568-04:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" state=STATUS_PLAYING positionMs=968 volume=50 May 25 23:15:00 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:00.569-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" id="mnt/INTERNAL/Gentle Ocean Surf.mp3" title="Gentle Ocean Surf.mp3" May 25 23:15:00 porch-speaker volumio[5490]: info: ------------------------------ 43ms May 25 23:15:01 porch-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. May 25 23:15:01 porch-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 23:15:01 porch-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 23:15:01 porch-speaker go-librespot[5846]: go-librespot daemon starting... May 25 23:15:01 porch-speaker go-librespot[5847]: time="2026-05-25T23:15:01-04:00" level=info msg="running go-librespot 0.7.1" May 25 23:15:01 porch-speaker go-librespot[5847]: time="2026-05-25T23:15:01-04:00" level=debug msg="app state loaded" May 25 23:15:01 porch-speaker go-librespot[5847]: time="2026-05-25T23:15:01-04:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 23:15:11 porch-speaker systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 23:15:11 porch-speaker systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 25 23:15:11 porch-speaker systemd[1]: welcome.service: Deactivated successfully. May 25 23:15:11 porch-speaker systemd[1]: Stopped welcome.service - Show a welcome message on console. May 25 23:15:11 porch-speaker systemd[1]: Stopping welcome.service - Show a welcome message on console... May 25 23:15:11 porch-speaker systemd[1]: Starting welcome.service - Show a welcome message on console... May 25 23:15:11 porch-speaker welcome[5887]: Resolved ip:[1] 10.0.0.103 May 25 23:15:11 porch-speaker systemd[1]: Finished welcome.service - Show a welcome message on console. May 25 23:15:11 porch-speaker systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 23:15:11 porch-speaker volumio[5490]: info: Received Get System Info May 25 23:15:11 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 23:15:11 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 23:15:11 porch-speaker volumio[5490]: info: Discovery: Getting this device information May 25 23:15:11 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioGetState May 25 23:15:11 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:11 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 23:15:11 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 23:15:11 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 23:15:11 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:11.534-04:00 level=INFO msg="emitting wifi info changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" available=true connected=true macAddress=e4:5f:01:7a:78:bd ip4Address=10.0.0.103/24 ip6Address= ssid=bhobbs.net May 25 23:15:12 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:12.519-04:00 level=INFO msg="service successfully established" component=discovery/localnet May 25 23:15:12 porch-speaker volumio[5490]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io May 25 23:15:12 porch-speaker key.dns_resolver[5844]: ad.bhobbs.net: No address associated with name May 25 23:15:12 porch-speaker volumio[5490]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 25 23:15:15 porch-speaker kernel: CIFS: VFS: \\ad.bhobbs.net has not responded in 180 seconds. Reconnecting... May 25 23:15:17 porch-speaker volumio[5490]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.103&uuid=dc2f2ace32e72b750799042711a95e17" http://updates.volumio.org/downloader-v1/track-device May 25 23:15:17 porch-speaker volumio[5490]: % Total % Received % Xferd Average Speed Time Time Time Current May 25 23:15:17 porch-speaker volumio[5490]: Dload Upload Total Spent Left Speed May 25 23:15:17 porch-speaker volumio[5490]: [1.5K blob data] May 25 23:15:17 porch-speaker volumio[5490]: retrying in 5 seconds, trial 1 May 25 23:15:17 porch-speaker volumio[5490]: info: Volumio Calling Home May 25 23:15:22 porch-speaker go-librespot[5847]: time="2026-05-25T23:15:22-04:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 25 23:15:22 porch-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 23:15:22 porch-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 23:15:25 porch-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. May 25 23:15:25 porch-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 23:15:25 porch-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 23:15:25 porch-speaker go-librespot[5913]: go-librespot daemon starting... May 25 23:15:25 porch-speaker go-librespot[5914]: time="2026-05-25T23:15:25-04:00" level=info msg="running go-librespot 0.7.1" May 25 23:15:25 porch-speaker go-librespot[5914]: time="2026-05-25T23:15:25-04:00" level=debug msg="app state loaded" May 25 23:15:25 porch-speaker go-librespot[5914]: time="2026-05-25T23:15:25-04:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 23:15:36 porch-speaker volumio[5490]: info: Discovery: this is already registered, d016a601-afd6-4653-b532-f23de15e9319 May 25 23:15:36 porch-speaker volumio[5490]: info: Discovery: Found device porch-speaker May 25 23:15:36 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioGetState May 25 23:15:36 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:37 porch-speaker key.dns_resolver[5894]: ad.bhobbs.net: No address associated with name May 25 23:15:38 porch-speaker kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 25 23:15:38 porch-speaker kernel: CIFS: VFS: \\ad.bhobbs.net Send error in SessSetup = -13 May 25 23:15:38 porch-speaker kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 25 23:15:38 porch-speaker kernel: CIFS: VFS: \\ad.bhobbs.net Send error in SessSetup = -13 May 25 23:15:40 porch-speaker kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 25 23:15:40 porch-speaker kernel: CIFS: VFS: \\ad.bhobbs.net Send error in SessSetup = -13 May 25 23:15:40 porch-speaker kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 25 23:15:40 porch-speaker kernel: CIFS: VFS: \\ad.bhobbs.net Send error in SessSetup = -13 May 25 23:15:41 porch-speaker systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 23:15:41 porch-speaker systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 25 23:15:41 porch-speaker systemd[1]: welcome.service: Deactivated successfully. May 25 23:15:41 porch-speaker systemd[1]: Stopped welcome.service - Show a welcome message on console. May 25 23:15:41 porch-speaker systemd[1]: Stopping welcome.service - Show a welcome message on console... May 25 23:15:41 porch-speaker systemd[1]: Starting welcome.service - Show a welcome message on console... May 25 23:15:41 porch-speaker welcome[5969]: Resolved ip:[1] 10.0.0.103 May 25 23:15:41 porch-speaker systemd[1]: Finished welcome.service - Show a welcome message on console. May 25 23:15:41 porch-speaker systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 23:15:41 porch-speaker volumio[5490]: info: Received Get System Info May 25 23:15:41 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 23:15:41 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 23:15:41 porch-speaker volumio[5490]: info: Discovery: Getting this device information May 25 23:15:41 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioGetState May 25 23:15:41 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:41 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 23:15:41 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:41.555-04:00 level=INFO msg="emitting wifi info changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" available=true connected=true macAddress=e4:5f:01:7a:78:bd ip4Address=10.0.0.103/24 ip6Address= ssid=bhobbs.net May 25 23:15:41 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 23:15:41 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 23:15:42 porch-speaker kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 25 23:15:42 porch-speaker kernel: CIFS: VFS: \\ad.bhobbs.net Send error in SessSetup = -13 May 25 23:15:42 porch-speaker kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 25 23:15:42 porch-speaker kernel: CIFS: VFS: \\ad.bhobbs.net Send error in SessSetup = -13 May 25 23:15:42 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:42.441-04:00 level=INFO msg="service successfully established" component=discovery/localnet May 25 23:15:44 porch-speaker kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 25 23:15:44 porch-speaker kernel: CIFS: VFS: \\ad.bhobbs.net Send error in SessSetup = -13 May 25 23:15:44 porch-speaker kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE May 25 23:15:44 porch-speaker kernel: CIFS: VFS: \\ad.bhobbs.net Send error in SessSetup = -13 May 25 23:15:44 porch-speaker go-librespot[5914]: time="2026-05-25T23:15:44-04:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 25 23:15:44 porch-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 23:15:44 porch-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 23:15:46 porch-speaker volumio[5490]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.103&uuid=dc2f2ace32e72b750799042711a95e17" http://updates.volumio.org/downloader-v1/track-device May 25 23:15:46 porch-speaker volumio[5490]: % Total % Received % Xferd Average Speed Time Time Time Current May 25 23:15:46 porch-speaker volumio[5490]: Dload Upload Total Spent Left Speed May 25 23:15:46 porch-speaker volumio[5490]: [1.5K blob data] May 25 23:15:46 porch-speaker volumio[5490]: retrying in 5 seconds, trial 2 May 25 23:15:46 porch-speaker volumio[5490]: info: Volumio Calling Home May 25 23:15:47 porch-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. May 25 23:15:47 porch-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 23:15:47 porch-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 23:15:47 porch-speaker go-librespot[5979]: go-librespot daemon starting... May 25 23:15:47 porch-speaker go-librespot[5980]: time="2026-05-25T23:15:47-04:00" level=info msg="running go-librespot 0.7.1" May 25 23:15:47 porch-speaker go-librespot[5980]: time="2026-05-25T23:15:47-04:00" level=debug msg="app state loaded" May 25 23:15:47 porch-speaker go-librespot[5980]: time="2026-05-25T23:15:47-04:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 23:15:50 porch-speaker go-librespot[5980]: time="2026-05-25T23:15:50-04:00" level=debug msg="new websocket client" May 25 23:15:50 porch-speaker volumio[5490]: info: Connection to go-librespot Websocket established May 25 23:15:53 porch-speaker volumio[5490]: info: Getting Spotify volume May 25 23:15:55 porch-speaker wpa_supplicant[1176]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:c9:e3:c7:a0:77 reason=1 May 25 23:15:55 porch-speaker wpa_supplicant[1176]: BSSID c0:c9:e3:c7:a0:77 ignore list count incremented to 2, ignoring for 10 seconds May 25 23:15:55 porch-speaker dhcpcd[898]: wlan0: carrier lost May 25 23:15:55 porch-speaker wpa_supplicant[1176]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 25 23:15:55 porch-speaker wpa_supplicant[1176]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US May 25 23:15:55 porch-speaker avahi-daemon[1301]: Withdrawing address record for 10.0.0.103 on wlan0. May 25 23:15:55 porch-speaker avahi-daemon[1301]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.0.0.103. May 25 23:15:55 porch-speaker avahi-daemon[1301]: Interface wlan0.IPv4 no longer relevant for mDNS. May 25 23:15:55 porch-speaker dhcpcd[898]: wlan0: deleting route to 10.0.0.0/24 May 25 23:15:55 porch-speaker dhcpcd[898]: wlan0: deleting default route via 10.0.0.34 May 25 23:15:55 porch-speaker volumio[5490]: info: Discovery: A device disappeared from network May 25 23:15:55 porch-speaker volumio[5490]: info: Discovery: Device porch-speaker disappeared from network May 25 23:15:55 porch-speaker volumio[5490]: info: Discovery: A device disappeared from network May 25 23:15:55 porch-speaker volumio[5490]: info: Discovery: Device test-speaker disappeared from network May 25 23:15:55 porch-speaker volumio[5490]: info: Discovery: Disconnected from remote: 10.0.0.110 May 25 23:15:55 porch-speaker systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 23:15:55 porch-speaker systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 25 23:15:55 porch-speaker systemd[1]: welcome.service: Deactivated successfully. May 25 23:15:55 porch-speaker systemd[1]: Stopped welcome.service - Show a welcome message on console. May 25 23:15:55 porch-speaker systemd[1]: Stopping welcome.service - Show a welcome message on console... May 25 23:15:55 porch-speaker systemd[1]: Starting welcome.service - Show a welcome message on console... May 25 23:15:55 porch-speaker welcome[6035]: Resolved ip:[0] May 25 23:15:55 porch-speaker systemd[1]: Finished welcome.service - Show a welcome message on console. May 25 23:15:55 porch-speaker systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 23:15:55 porch-speaker wpa_supplicant[1176]: wlan0: Trying to associate with c0:c9:e3:c7:a0:bf (SSID='bhobbs.net' freq=5200 MHz) May 25 23:15:55 porch-speaker volumio[5490]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 25 23:15:55 porch-speaker volumio[5490]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 25 23:15:55 porch-speaker volumio[5490]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 25 23:15:55 porch-speaker volumio[5490]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 25 23:15:55 porch-speaker wpa_supplicant[1176]: wlan0: Associated with c0:c9:e3:c7:a0:bf May 25 23:15:55 porch-speaker wpa_supplicant[1176]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 25 23:15:55 porch-speaker wpa_supplicant[1176]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US May 25 23:15:55 porch-speaker wpa_supplicant[1176]: wlan0: WPA: Key negotiation completed with c0:c9:e3:c7:a0:bf [PTK=CCMP GTK=CCMP] May 25 23:15:55 porch-speaker wpa_supplicant[1176]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:c9:e3:c7:a0:bf completed [id=0 id_str=] May 25 23:15:55 porch-speaker dhcpcd[898]: wlan0: carrier acquired May 25 23:15:55 porch-speaker dhcpcd[898]: wlan0: IAID 01:7a:78:bd May 25 23:15:55 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioGetState May 25 23:15:55 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:55 porch-speaker volumio[5490]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 May 25 23:15:55 porch-speaker volumio[5490]: SPOTIFY: SPOTIFY VOLUME undefined May 25 23:15:55 porch-speaker volumio[5490]: SPOTIFY: VOLUMIO VOLUME 50 May 25 23:15:55 porch-speaker volumio[5490]: info: Aligning Spotify Volume to Volumio Volume May 25 23:15:55 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioGetState May 25 23:15:55 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:55 porch-speaker volumio[5490]: info: Setting Spotify Volume from Volumio: 50 May 25 23:15:56 porch-speaker wpa_supplicant[1176]: RRM: Ignoring radio measurement request: Not RRM network May 25 23:15:56 porch-speaker wpa_supplicant[1176]: wlan0: WNM: Preferred List Available May 25 23:15:56 porch-speaker kernel: ieee80211 phy0: brcmf_p2p_send_action_frame: Unknown Frame: category 0xa, action 0x8 May 25 23:15:56 porch-speaker nmbd[1083]: [2026/05/25 23:15:56.126513, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 25 23:15:56 porch-speaker nmbd[1083]: Packet send failed to 10.0.0.255(138) ERRNO=Network is unreachable May 25 23:15:56 porch-speaker dhcpcd[898]: wlan0: rebinding lease of 10.0.0.103 May 25 23:15:56 porch-speaker dhcpcd[898]: wlan0: NAK: from 10.0.0.11 May 25 23:15:56 porch-speaker dhcpcd[898]: wlan0: soliciting a DHCP lease May 25 23:15:56 porch-speaker dhcpcd[898]: wlan0: offered 10.0.0.21 from 10.0.0.11 May 25 23:15:56 porch-speaker dhcpcd[898]: wlan0: probing address 10.0.0.21/24 May 25 23:15:56 porch-speaker volumio[5490]: info: Received Get System Info May 25 23:15:56 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 23:15:56 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 23:15:56 porch-speaker volumio[5490]: info: Discovery: Getting this device information May 25 23:15:56 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioGetState May 25 23:15:56 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:56 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 23:15:56 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 23:15:56 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:56.627-04:00 level=INFO msg="emitting wifi info changed event" component=server peer="10.0.0.57:35632 @ 0x2eb8270" available=true connected=true macAddress=e4:5f:01:7a:78:bd ip4Address= ip6Address= ssid=bhobbs.net May 25 23:15:56 porch-speaker volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 23:15:56 porch-speaker dhcpcd[898]: wlan0: soliciting an IPv6 router May 25 23:15:57 porch-speaker volumio5-onboarding[1593]: time=2026-05-25T23:15:57.432-04:00 level=INFO msg="service successfully established" component=discovery/localnet May 25 23:15:57 porch-speaker volumio[5490]: SPOTIFY: SETTING SPOTIFY VOLUME 50 May 25 23:15:57 porch-speaker volumio[5490]: info: Sending Spotify command with payload to local API: /player/volume May 25 23:15:57 porch-speaker volumio[5490]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 25 23:15:57 porch-speaker volumio[5490]: info: CoreCommandRouter::volumioGetState May 25 23:15:57 porch-speaker volumio[5490]: info: CorePlayQueue::getTrack 0 May 25 23:15:58 porch-speaker ntpd[1005]: IO: Deleting interface #8 wlan0, 10.0.0.103#123, interface stats: received=0, sent=5, dropped=0, active_time=859 secs May 25 23:15:58 porch-speaker ntpd[1005]: PROTO: 23.157.160.168 unlink local addr 10.0.0.103 -> May 25 23:15:58 porch-speaker ntpd[1005]: PROTO: 23.186.168.127 unlink local addr 10.0.0.103 -> May 25 23:15:58 porch-speaker ntpd[1005]: PROTO: 162.159.200.1 unlink local addr 10.0.0.103 -> May 25 23:15:58 porch-speaker ntpd[1005]: PROTO: 172.233.153.85 unlink local addr 10.0.0.103 -> May 25 23:15:58 porch-speaker ntpd[1005]: PROTO: 208.113.130.146 unlink local addr 10.0.0.103 -> May 25 23:15:58 porch-speaker ntpd[1005]: PROTO: 23.186.168.124 unlink local addr 10.0.0.103 -> May 25 23:15:58 porch-speaker ntpd[1005]: PROTO: 23.186.168.123 unlink local addr 10.0.0.103 -> May 25 23:16:00 porch-speaker bash[5377]: setdatetime-helper: all HTTPS Date fallbacks failed May 25 23:16:00 porch-speaker systemd[1]: setdatetime-helper.service: Deactivated successfully. May 25 23:16:00 porch-speaker systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 25 23:16:00 porch-speaker volumio[5490]: info: Volumio Network Manager: Network status updated: 0 May 25 23:16:01 porch-speaker go-librespot[5980]: time="2026-05-25T23:16:01-04:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 25 23:16:01 porch-speaker volumio[5490]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up May 25 23:16:01 porch-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 23:16:01 porch-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 23:16:01 porch-speaker volumio[5490]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 23:16:01 porch-speaker volumio[5490]: Error: socket hang up May 25 23:16:01 porch-speaker volumio[5490]: at connResetException (node:internal/errors:720:14) May 25 23:16:01 porch-speaker volumio[5490]: at Socket.socketOnEnd (node:_http_client:519:23) May 25 23:16:01 porch-speaker volumio[5490]: at Socket.emit (node:events:526:35) May 25 23:16:01 porch-speaker volumio[5490]: at endReadableNT (node:internal/streams/readable:1376:12) May 25 23:16:01 porch-speaker volumio[5490]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { May 25 23:16:01 porch-speaker volumio[5490]: code: 'ECONNRESET', May 25 23:16:01 porch-speaker volumio[5490]: response: undefined May 25 23:16:01 porch-speaker volumio[5490]: } May 25 23:16:01 porch-speaker volumio[5490]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 23:16:01 porch-speaker dhcpcd[898]: wlan0: leased 10.0.0.21 for 691200 seconds May 25 23:16:01 porch-speaker avahi-daemon[1301]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.0.21. May 25 23:16:01 porch-speaker avahi-daemon[1301]: New relevant interface wlan0.IPv4 for mDNS. May 25 23:16:01 porch-speaker avahi-daemon[1301]: Registering new address record for 10.0.0.21 on wlan0.IPv4. May 25 23:16:01 porch-speaker dhcpcd[898]: wlan0: adding route to 10.0.0.0/24 May 25 23:16:01 porch-speaker dhcpcd[898]: wlan0: adding default route via 10.0.0.1 May 25 23:16:01 porch-speaker systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 23:16:01 porch-speaker systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 25 23:16:01 porch-speaker systemd[1]: welcome.service: Deactivated successfully. May 25 23:16:01 porch-speaker systemd[1]: Stopped welcome.service - Show a welcome message on console. May 25 23:16:01 porch-speaker systemd[1]: Stopping welcome.service - Show a welcome message on console... May 25 23:16:01 porch-speaker systemd[1]: Starting welcome.service - Show a welcome message on console... May 25 23:16:01 porch-speaker welcome[6117]: Resolved ip:[1] 10.0.0.21 May 25 23:16:01 porch-speaker systemd[1]: Finished welcome.service - Show a welcome message on console. May 25 23:16:01 porch-speaker systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 25 23:16:01 porch-speaker sudo[6130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 23:15' May 25 23:16:01 porch-speaker sudo[6130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"