Jan 10 20:38:11 volumio-manu volumio[1180]: verbose: New Socket.io Connection to 192.168.1.96 from 192.168.1.9 UA: Mozilla/5.0 (Linux; Android 15; FP4 Build/FP4.SREL.15.14.4; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioGetVisibleSources Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioGetState Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 10 20:38:11 volumio-manu volumio[1180]: info: Received Get System Info Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 10 20:38:11 volumio-manu volumio[1180]: info: Discovery: Getting this device information Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioGetState Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 10 20:38:11 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioGetState Jan 10 20:38:12 volumio-manu volumio[1180]: info: Listing playlists Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::getQueue Jan 10 20:38:12 volumio-manu volumio[1180]: info: CorePlayQueue::getQueue Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 10 20:38:12 volumio-manu volumio[1180]: info: Preload queue cleared Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::ClearQueue Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::stop Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::serviceStop Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreCommandRouter::serviceStop Jan 10 20:38:12 volumio-manu volumio[1180]: info: Airplay Stop Jan 10 20:38:12 volumio-manu volumio[1180]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jan 10 20:38:12 volumio-manu volumio[1180]: info: CorePlayQueue::clearPlayQueue Jan 10 20:38:12 volumio-manu volumio[1180]: info: CorePlayQueue::saveQueue Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushQueue Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::addQueueItems Jan 10 20:38:12 volumio-manu volumio[1180]: info: CorePlayQueue::addQueueItems Jan 10 20:38:12 volumio-manu volumio[1180]: info: Preload queue cleared Jan 10 20:38:12 volumio-manu volumio[1180]: info: Adding Item to queue: https://stream.radiofrance.fr/fipcultes/fipcultes.m3u8?id=radiofranceBose Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushQueue Jan 10 20:38:12 volumio-manu volumio[1180]: info: CorePlayQueue::saveQueue Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::updateTrackBlock Jan 10 20:38:12 volumio-manu volumio[1180]: info: CorePlayQueue::getTrackBlock Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPlay Jan 10 20:38:12 volumio-manu volumio[1180]: verbose: UNSET VOLATILE: Service: airplay_emulation Jan 10 20:38:12 volumio-manu volumio[1180]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::play index 0 Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::stop Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::play index undefined Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 20:38:12 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:12 volumio-manu volumio[1180]: info: CoreStateMachine::startPlaybackTimer Jan 10 20:38:12 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:12 volumio-manu volumio[1180]: info: [1768073892981] ControllerWebradio::clearAddPlayTrack Jan 10 20:38:12 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 20:38:12 volumio-manu volumio[1180]: info: sendMpdCommand stop took 11 milliseconds Jan 10 20:38:12 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 20:38:12 volumio-manu volumio[1180]: info: Jan 10 20:38:12 volumio-manu volumio[1180]: ---------------------------- MPD announces system playlist update Jan 10 20:38:12 volumio-manu volumio[1180]: info: Ignoring MPD Status Update Jan 10 20:38:12 volumio-manu volumio[1180]: info: sendMpdCommand clear took 3 milliseconds Jan 10 20:38:12 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radiofrance.fr/fipcultes/fipcultes.m3u8?id=radiofranceBose" Jan 10 20:38:12 volumio-manu volumio[1180]: info: Jan 10 20:38:12 volumio-manu volumio[1180]: ---------------------------- MPD announces system playlist update Jan 10 20:38:12 volumio-manu volumio[1180]: info: Ignoring MPD Status Update Jan 10 20:38:13 volumio-manu volumio[1180]: info: Jan 10 20:38:13 volumio-manu volumio[1180]: ---------------------------- MPD announces system playlist update Jan 10 20:38:13 volumio-manu volumio[1180]: info: Ignoring MPD Status Update Jan 10 20:38:13 volumio-manu volumio[1180]: error: updateQueue error: null Jan 10 20:38:13 volumio-manu volumio[1180]: info: Jan 10 20:38:13 volumio-manu volumio[1180]: ---------------------------- MPD announces system playlist update Jan 10 20:38:13 volumio-manu volumio[1180]: info: Ignoring MPD Status Update Jan 10 20:38:13 volumio-manu volumio[1180]: info: ------------------------------ 11ms Jan 10 20:38:13 volumio-manu volumio[1180]: info: sendMpdCommand add "https://stream.radiofrance.fr/fipcultes/fipcultes.m3u8?id=radiofranceBose" took 10 milliseconds Jan 10 20:38:13 volumio-manu volumio[1180]: info: ------------------------------ 9ms Jan 10 20:38:13 volumio-manu volumio[1180]: info: ------------------------------ 8ms Jan 10 20:38:13 volumio-manu volumio[1180]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 10 20:38:13 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand play Jan 10 20:38:13 volumio-manu volumio[1180]: info: Jan 10 20:38:13 volumio-manu volumio[1180]: ---------------------------- MPD announces system playlist update Jan 10 20:38:13 volumio-manu volumio[1180]: info: Ignoring MPD Status Update Jan 10 20:38:13 volumio-manu volumio[1180]: info: Jan 10 20:38:13 volumio-manu volumio[1180]: ---------------------------- MPD announces system playlist update Jan 10 20:38:13 volumio-manu volumio[1180]: info: Ignoring MPD Status Update Jan 10 20:38:13 volumio-manu volumio[1180]: info: ------------------------------ 15ms Jan 10 20:38:13 volumio-manu volumio[1180]: info: sendMpdCommand play took 11 milliseconds Jan 10 20:38:13 volumio-manu volumio[1180]: info: ------------------------------ 11ms Jan 10 20:38:13 volumio-manu volumio[1180]: info: ------------------------------ 10ms Jan 10 20:38:13 volumio-manu sudo[2786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 2264 Jan 10 20:38:13 volumio-manu sudo[2789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 2264 Jan 10 20:38:13 volumio-manu sudo[2786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 20:38:13 volumio-manu sudo[2789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 20:38:13 volumio-manu sudo[2786]: pam_unix(sudo:session): session closed for user root Jan 10 20:38:13 volumio-manu sudo[2789]: pam_unix(sudo:session): session closed for user root Jan 10 20:38:13 volumio-manu shairport-sync[2550]: {"time":1768073755830,"response":"airplayInactive Success"} Jan 10 20:38:13 volumio-manu systemd[1]: shairport-sync.service: Main process exited, code=killed, status=12/USR2 Jan 10 20:38:13 volumio-manu volumio[1180]: info: Shairport-Sync paused with USR2 Jan 10 20:38:13 volumio-manu systemd[1]: shairport-sync.service: Failed with result 'signal'. Jan 10 20:38:13 volumio-manu systemd[1]: shairport-sync.service: Consumed 47.125s CPU time. Jan 10 20:38:13 volumio-manu volumio[1180]: info: Shairport-Sync paused with USR2 Jan 10 20:38:14 volumio-manu volumio[1180]: info: Restarting Shairport-Sync after stop Jan 10 20:38:14 volumio-manu volumio[1180]: info: Restarting Shairport-Sync after stop Jan 10 20:38:14 volumio-manu sudo[2797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 10 20:38:14 volumio-manu sudo[2797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 20:38:14 volumio-manu sudo[2799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 10 20:38:14 volumio-manu sudo[2799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 20:38:14 volumio-manu systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 10 20:38:14 volumio-manu sudo[2797]: pam_unix(sudo:session): session closed for user root Jan 10 20:38:14 volumio-manu sudo[2799]: pam_unix(sudo:session): session closed for user root Jan 10 20:38:14 volumio-manu volumio[1180]: info: Shairport-Sync retarted Jan 10 20:38:14 volumio-manu volumio[1180]: info: Shairport-Sync retarted Jan 10 20:38:14 volumio-manu volumio[1180]: info: Jan 10 20:38:14 volumio-manu volumio[1180]: ---------------------------- MPD announces state update: player Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::getState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:38:14 volumio-manu volumio[1180]: info: Jan 10 20:38:14 volumio-manu volumio[1180]: ---------------------------- MPD announces state update: player Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::getState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:38:14 volumio-manu volumio[1180]: info: Jan 10 20:38:14 volumio-manu volumio[1180]: ---------------------------- MPD announces state update: player Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::getState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:38:14 volumio-manu volumio[1180]: info: Jan 10 20:38:14 volumio-manu volumio[1180]: ---------------------------- MPD announces state update: player Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand status took 26 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::getState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:38:14 volumio-manu volumio[1180]: info: Jan 10 20:38:14 volumio-manu volumio[1180]: ---------------------------- MPD announces state update: player Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::getState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand status took 31 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand status took 29 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand status took 7 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand status took 4 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::servicePushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fipcultes.m3u8?id=radiofranceBose","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fipcultes/fipcultes.m3u8?id=radiofranceBose","trackType":""} Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: CURRENT POSITION 0 Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::syncState stateService play Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::syncState currentStatus stop Jan 10 20:38:14 volumio-manu volumio[1180]: info: ------------------------------ 47ms Jan 10 20:38:14 volumio-manu volumio[1180]: info: Jan 10 20:38:14 volumio-manu volumio[1180]: ---------------------------- MPD announces state update: player Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::getState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: info: sendMpdCommand status took 4 milliseconds Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::parseState Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::servicePushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":484,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fipcultes.m3u8?id=radiofranceBose","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fipcultes/fipcultes.m3u8?id=radiofranceBose","trackType":""} Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: CURRENT POSITION 0 Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::syncState stateService play Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jan 10 20:38:14 volumio-manu volumio[1180]: info: Received an update from plugin. extracting info from payload Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::servicePushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":484,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fipcultes.m3u8?id=radiofranceBose","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fipcultes/fipcultes.m3u8?id=radiofranceBose","trackType":""} Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: CURRENT POSITION 0 Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::syncState stateService play Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jan 10 20:38:14 volumio-manu volumio[1180]: info: Received an update from plugin. extracting info from payload Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::servicePushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fipcultes.m3u8?id=radiofranceBose","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fipcultes/fipcultes.m3u8?id=radiofranceBose","trackType":""} Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: CURRENT POSITION 0 Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::syncState stateService play Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jan 10 20:38:14 volumio-manu volumio[1180]: info: Received an update from plugin. extracting info from payload Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: ControllerMpd::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::servicePushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fipcultes.m3u8?id=radiofranceBose","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fipcultes/fipcultes.m3u8?id=radiofranceBose","trackType":""} Jan 10 20:38:14 volumio-manu volumio[1180]: verbose: CURRENT POSITION 0 Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::syncState stateService play Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jan 10 20:38:14 volumio-manu volumio[1180]: info: Received an update from plugin. extracting info from payload Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:14 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:15 volumio-manu volumio[1180]: info: ------------------------------ 237ms Jan 10 20:38:15 volumio-manu volumio[1180]: info: ------------------------------ 236ms Jan 10 20:38:15 volumio-manu volumio[1180]: info: ------------------------------ 216ms Jan 10 20:38:15 volumio-manu volumio[1180]: info: ------------------------------ 212ms Jan 10 20:38:15 volumio-manu volumio[1180]: info: sendMpdCommand playlistinfo took 192 milliseconds Jan 10 20:38:15 volumio-manu volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jan 10 20:38:15 volumio-manu volumio[1180]: info: ControllerMpd::pushState Jan 10 20:38:15 volumio-manu volumio[1180]: info: CoreCommandRouter::servicePushState Jan 10 20:38:15 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:15 volumio-manu volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":989,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fipcultes.m3u8?id=radiofranceBose","artist":null,"album":null,"uri":"https://stream.radiofrance.fr/fipcultes/fipcultes.m3u8?id=radiofranceBose","trackType":""} Jan 10 20:38:15 volumio-manu volumio[1180]: verbose: CURRENT POSITION 0 Jan 10 20:38:15 volumio-manu volumio[1180]: info: CoreStateMachine::syncState stateService play Jan 10 20:38:15 volumio-manu volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jan 10 20:38:15 volumio-manu volumio[1180]: info: Received an update from plugin. extracting info from payload Jan 10 20:38:15 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:15 volumio-manu volumio[1180]: info: CorePlayQueue::getTrack 0 Jan 10 20:38:15 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:38:15 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:15 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:15 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:15 volumio-manu volumio[1180]: info: ------------------------------ 253ms Jan 10 20:38:15 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 20:38:15 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 20:38:15 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 20:38:15 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 20:38:15 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 20:38:15 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 20:38:15 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 20:38:15 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 20:38:15 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 20:38:15 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 10 20:38:17 volumio-manu volumio[1180]: info: VolumeController::SetAlsaVolume99 Jan 10 20:38:17 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:17 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:38:17 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:17 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Jan 10 20:38:17 volumio-manu volumio[1180]: SPOTIFY: SPOTIFY VOLUME 100 Jan 10 20:38:17 volumio-manu volumio[1180]: SPOTIFY: VOLUMIO VOLUME 99 Jan 10 20:38:17 volumio-manu volumio[1180]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 10 20:38:17 volumio-manu volumio[1180]: info: VolumeController::SetAlsaVolume80 Jan 10 20:38:17 volumio-manu volumio[1180]: info: CoreStateMachine::pushState Jan 10 20:38:17 volumio-manu volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 20:38:17 volumio-manu volumio[1180]: info: CoreCommandRouter::volumioPushState Jan 10 20:38:17 volumio-manu volumio[1180]: SPOTIFY: RECEIVED VOLUMIO VOLUME 80 Jan 10 20:38:17 volumio-manu volumio[1180]: SPOTIFY: SPOTIFY VOLUME 100 Jan 10 20:38:17 volumio-manu volumio[1180]: SPOTIFY: VOLUMIO VOLUME 80 Jan 10 20:38:17 volumio-manu volumio[1180]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 10 20:38:17 volumio-manu volumio[1180]: info: Setting Spotify Volume from Volumio: 80 Jan 10 20:38:19 volumio-manu volumio[1180]: SPOTIFY: SETTING SPOTIFY VOLUME 80 Jan 10 20:38:19 volumio-manu volumio[1180]: info: Sending Spotify command with payload to local API: /player/volume Jan 10 20:38:24 volumio-manu go-librespot[1526]: time="2026-01-10T20:38:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 10 20:38:24 volumio-manu systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 10 20:38:24 volumio-manu volumio[1180]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Jan 10 20:38:24 volumio-manu systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 10 20:38:24 volumio-manu volumio[1180]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Jan 10 20:38:24 volumio-manu volumio[1180]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 20:38:24 volumio-manu volumio[1180]: Error: socket hang up Jan 10 20:38:24 volumio-manu volumio[1180]: at connResetException (node:internal/errors:720:14) Jan 10 20:38:24 volumio-manu volumio[1180]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 10 20:38:24 volumio-manu volumio[1180]: at Socket.emit (node:events:526:35) Jan 10 20:38:24 volumio-manu volumio[1180]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 10 20:38:24 volumio-manu volumio[1180]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 10 20:38:24 volumio-manu volumio[1180]: code: 'ECONNRESET', Jan 10 20:38:24 volumio-manu volumio[1180]: response: undefined Jan 10 20:38:24 volumio-manu volumio[1180]: } Jan 10 20:38:24 volumio-manu volumio[1180]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 20:38:24 volumio-manu systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 10 20:38:25 volumio-manu sudo[2894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-10 20:37' Jan 10 20:38:25 volumio-manu sudo[2894]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"