-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2025-09-04 09:40:15 CEST. -- Sep 04 09:39:38 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 09:39:38 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 09:39:38 volumio volumio[835]: info: Discovery: Getting this device information Sep 04 09:39:38 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:39:38 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:38 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 09:39:38 volumio volumio[835]: verbose: New Socket.io Connection to 192.168.0.103:3000 from 192.168.0.107 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Sep 04 09:39:38 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:39:38 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:38 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 09:39:38 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 09:39:40 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:39:40 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:41 volumio volumio[835]: verbose: New Socket.io Connection to 192.168.0.103:3000 from 192.168.0.107 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Sep 04 09:39:41 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:39:41 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:41 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 09:39:41 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 09:39:41 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:39:41 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:42 volumio sudo[26749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 09:39:42 volumio sudo[26749]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 09:39:42 volumio sudo[26749]: pam_unix(sudo:session): session closed for user root Sep 04 09:39:42 volumio sudo[26752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 09:39:42 volumio sudo[26752]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 09:39:42 volumio sudo[26752]: pam_unix(sudo:session): session closed for user root Sep 04 09:39:42 volumio volumio[835]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 14; SM-A528B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.158 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 04 09:39:42 volumio sudo[26755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 09:39:42 volumio sudo[26755]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 09:39:42 volumio sudo[26755]: pam_unix(sudo:session): session closed for user root Sep 04 09:39:42 volumio sudo[26758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 09:39:42 volumio sudo[26758]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 09:39:42 volumio sudo[26758]: pam_unix(sudo:session): session closed for user root Sep 04 09:39:42 volumio volumio[835]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.107 UA: Mozilla/5.0 (Linux; Android 14; SM-A528B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.158 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::volumioGetVisibleSources Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:39:42 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 04 09:39:42 volumio volumio[835]: info: Received Get System Info Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 09:39:42 volumio volumio[835]: info: Discovery: Getting this device information Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:39:42 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:39:42 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:42 volumio volumio[835]: info: Listing playlists Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 04 09:39:42 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 09:39:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 04 09:39:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 09:39:44 volumio volumio[835]: info: Received Get System Info Sep 04 09:39:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 09:39:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 09:39:44 volumio volumio[835]: info: Discovery: Getting this device information Sep 04 09:39:44 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:39:44 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 09:39:44 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 04 09:39:46 volumio volumio[835]: info: CoreCommandRouter::volumioPlay Sep 04 09:39:46 volumio volumio[835]: info: CoreStateMachine::play index undefined Sep 04 09:39:46 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 09:39:46 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:46 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Sep 04 09:39:46 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:46 volumio volumio[835]: info: [1756971586449] ControllerWebradio::clearAddPlayTrack Sep 04 09:39:46 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 09:39:46 volumio volumio[835]: info: sendMpdCommand stop took 1 milliseconds Sep 04 09:39:46 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Sep 04 09:39:46 volumio volumio[835]: info: Sep 04 09:39:46 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:39:46 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:39:46 volumio volumio[835]: info: sendMpdCommand clear took 1 milliseconds Sep 04 09:39:46 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand load "https://imagine897.radioca.st/;" Sep 04 09:39:46 volumio volumio[835]: info: Sep 04 09:39:46 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:39:46 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:39:46 volumio volumio[835]: info: Sep 04 09:39:46 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:39:46 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:39:46 volumio volumio[835]: error: updateQueue error: null Sep 04 09:39:46 volumio volumio[835]: info: ------------------------------ 4ms Sep 04 09:39:47 volumio volumio[835]: error: updateQueue error: null Sep 04 09:39:47 volumio volumio[835]: error: updateQueue error: null Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand add "https://imagine897.radioca.st/;" Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 762ms Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 763ms Sep 04 09:39:47 volumio volumio[835]: info: Sep 04 09:39:47 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:39:47 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand add "https://imagine897.radioca.st/;" took 4 milliseconds Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand play Sep 04 09:39:47 volumio volumio[835]: info: Sep 04 09:39:47 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:39:47 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:39:47 volumio volumio[835]: info: Sep 04 09:39:47 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:39:47 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 9ms Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand play took 5 milliseconds Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 5ms Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 4ms Sep 04 09:39:47 volumio volumio[835]: info: Sep 04 09:39:47 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:39:47 volumio volumio[835]: info: Sep 04 09:39:47 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:39:47 volumio volumio[835]: info: Sep 04 09:39:47 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand status took 12 milliseconds Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand status took 12 milliseconds Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 09:39:47 volumio volumio[835]: info: Sep 04 09:39:47 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand status took 14 milliseconds Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"https://imagine897.radioca.st/;","trackType":"st/;"} Sep 04 09:39:47 volumio volumio[835]: verbose: CURRENT POSITION 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus stop Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"https://imagine897.radioca.st/;","trackType":"st/;"} Sep 04 09:39:47 volumio volumio[835]: verbose: CURRENT POSITION 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Sep 04 09:39:47 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 24ms Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 25ms Sep 04 09:39:47 volumio volumio[835]: info: Sep 04 09:39:47 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:39:47 volumio volumio[835]: info: Sep 04 09:39:47 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand status took 14 milliseconds Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand status took 3 milliseconds Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"https://imagine897.radioca.st/;","trackType":"st/;"} Sep 04 09:39:47 volumio volumio[835]: verbose: CURRENT POSITION 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Sep 04 09:39:47 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 44ms Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 04 09:39:47 volumio volumio[835]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Sep 04 09:39:47 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"https://imagine897.radioca.st/;","trackType":"st/;"} Sep 04 09:39:47 volumio volumio[835]: verbose: CURRENT POSITION 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Sep 04 09:39:47 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"https://imagine897.radioca.st/;","trackType":"st/;"} Sep 04 09:39:47 volumio volumio[835]: verbose: CURRENT POSITION 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Sep 04 09:39:47 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:47 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"https://imagine897.radioca.st/;","trackType":"st/;"} Sep 04 09:39:47 volumio volumio[835]: verbose: CURRENT POSITION 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Sep 04 09:39:47 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:47 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:47 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:47 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 50ms Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 39ms Sep 04 09:39:47 volumio volumio[835]: info: ------------------------------ 38ms Sep 04 09:39:48 volumio volumio[835]: info: Sep 04 09:39:48 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:39:48 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:39:48 volumio volumio[835]: info: Sep 04 09:39:48 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:39:48 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:39:48 volumio volumio[835]: info: Sep 04 09:39:48 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:39:48 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:39:48 volumio volumio[835]: info: Sep 04 09:39:48 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:39:48 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:39:48 volumio volumio[835]: info: Sep 04 09:39:48 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:39:48 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:39:48 volumio volumio[835]: info: Sep 04 09:39:48 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:39:48 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:39:48 volumio volumio[835]: info: ------------------------------ 7ms Sep 04 09:39:48 volumio volumio[835]: info: sendMpdCommand status took 7 milliseconds Sep 04 09:39:48 volumio volumio[835]: info: ------------------------------ 6ms Sep 04 09:39:48 volumio volumio[835]: info: sendMpdCommand status took 4 milliseconds Sep 04 09:39:48 volumio volumio[835]: info: ------------------------------ 3ms Sep 04 09:39:48 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 09:39:48 volumio volumio[835]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 04 09:39:48 volumio volumio[835]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 04 09:39:48 volumio volumio[835]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Sep 04 09:39:48 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Sep 04 09:39:48 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:39:48 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:39:48 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:48 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":754,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Moby - Dream About Me","artist":"Netcast","album":null,"uri":"https://imagine897.radioca.st/;","trackType":"st/;"} Sep 04 09:39:48 volumio volumio[835]: verbose: CURRENT POSITION 0 Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Sep 04 09:39:48 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:48 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 09:39:48 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:48 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:48 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:39:48 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:39:48 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:48 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Moby - Dream About Me","artist":"Netcast","album":null,"uri":"https://imagine897.radioca.st/;","trackType":"st/;"} Sep 04 09:39:48 volumio volumio[835]: verbose: CURRENT POSITION 0 Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Sep 04 09:39:48 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:48 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:48 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:48 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:39:48 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:39:48 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:39:48 volumio volumio[835]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Moby - Dream About Me","artist":"Netcast","album":null,"uri":"https://imagine897.radioca.st/;","trackType":"st/;"} Sep 04 09:39:48 volumio volumio[835]: verbose: CURRENT POSITION 0 Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::syncState stateService play Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::syncState currentStatus play Sep 04 09:39:48 volumio volumio[835]: info: Received an update from plugin. extracting info from payload Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:48 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:48 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:39:48 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:39:48 volumio volumio[835]: info: ------------------------------ 32ms Sep 04 09:39:48 volumio volumio[835]: info: ------------------------------ 29ms Sep 04 09:39:48 volumio volumio[835]: info: ------------------------------ 28ms Sep 04 09:39:52 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 04 09:40:02 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 09:40:02 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 09:40:02 volumio volumio[835]: info: Discovery: Getting this device information Sep 04 09:40:02 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:40:02 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 09:40:02 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 09:40:02 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 09:40:02 volumio volumio[835]: info: Discovery: Getting this device information Sep 04 09:40:02 volumio volumio[835]: info: CoreCommandRouter::volumioGetState Sep 04 09:40:02 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 09:40:03 volumio volumio[835]: info: CoreCommandRouter::volumioGetQueue Sep 04 09:40:03 volumio volumio[835]: info: CoreStateMachine::getQueue Sep 04 09:40:03 volumio volumio[835]: info: CorePlayQueue::getQueue Sep 04 09:40:07 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 04 09:40:07 volumio volumio[835]: info: Preload queue cleared Sep 04 09:40:10 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 04 09:40:10 volumio volumio[835]: info: Preload queue cleared Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::ClearQueue Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::stop Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::stPlaybackTimer Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::serviceStop Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::serviceStop Sep 04 09:40:12 volumio volumio[835]: info: [1756971612919] ControllerWebradio::stop Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::clearPlayQueue Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::saveQueue Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushQueue Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::addQueueItems Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::addQueueItems Sep 04 09:40:12 volumio volumio[835]: info: Preload queue cleared Sep 04 09:40:12 volumio volumio[835]: info: Adding Item to queue: http://stream.radiojar.com/083wqknmsuhvv Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushQueue Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::saveQueue Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::updateTrackBlock Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::getTrackBlock Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPlay Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::play index 0 Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::stop Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::play index undefined Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::startPlaybackTimer Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:40:12 volumio volumio[835]: info: [1756971612929] ControllerWebradio::clearAddPlayTrack Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand stop Sep 04 09:40:12 volumio volumio[835]: info: sendMpdCommand stop took 20 milliseconds Sep 04 09:40:12 volumio volumio[835]: info: Sep 04 09:40:12 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:40:12 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:40:12 volumio volumio[835]: info: sendMpdCommand stop took 12 milliseconds Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand clear Sep 04 09:40:12 volumio volumio[835]: info: Sep 04 09:40:12 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:40:12 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:40:12 volumio volumio[835]: info: Sep 04 09:40:12 volumio volumio[835]: ---------------------------- MPD announces state update: player Sep 04 09:40:12 volumio volumio[835]: info: ControllerMpd::getState Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand status Sep 04 09:40:12 volumio volumio[835]: info: Sep 04 09:40:12 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:40:12 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:40:12 volumio volumio[835]: info: sendMpdCommand status took 4 milliseconds Sep 04 09:40:12 volumio volumio[835]: info: sendMpdCommand clear took 4 milliseconds Sep 04 09:40:12 volumio volumio[835]: info: sendMpdCommand status took 3 milliseconds Sep 04 09:40:12 volumio volumio[835]: info: sendMpdCommand status took 2 milliseconds Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radiojar.com/083wqknmsuhvv" Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::parseState Sep 04 09:40:12 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:40:12 volumio volumio[835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 04 09:40:12 volumio volumio[835]: info: ControllerMpd::pushState Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::servicePushState Sep 04 09:40:12 volumio volumio[835]: info: CoreStateMachine::pushState Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:40:12 volumio volumio[835]: info: CoreCommandRouter::volumioPushState Sep 04 09:40:12 volumio volumio[835]: info: CorePlayQueue::getTrack 0 Sep 04 09:40:12 volumio volumio[835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 04 09:40:12 volumio volumio[835]: info: ------------------------------ 18ms Sep 04 09:40:12 volumio volumio[835]: info: ------------------------------ 17ms Sep 04 09:40:12 volumio volumio[835]: info: Sep 04 09:40:12 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:40:12 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:40:12 volumio volumio[835]: info: Sep 04 09:40:12 volumio volumio[835]: ---------------------------- MPD announces system playlist update Sep 04 09:40:12 volumio volumio[835]: info: Ignoring MPD Status Update Sep 04 09:40:12 volumio volumio[835]: error: updateQueue error: null Sep 04 09:40:12 volumio volumio[835]: info: ------------------------------ 19ms Sep 04 09:40:12 volumio volumio[835]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 04 09:40:12 volumio volumio[835]: verbose: ControllerMpd::parseTrackInfo Sep 04 09:40:12 volumio volumio[835]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 04 09:40:12 volumio volumio[835]: info: ------------------------------ 24ms Sep 04 09:40:14 volumio volumio[835]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 09:40:14 volumio volumio[835]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Sep 04 09:40:14 volumio volumio[835]: code: 'auth/network-request-failed', Sep 04 09:40:14 volumio volumio[835]: a: null Sep 04 09:40:14 volumio volumio[835]: } Sep 04 09:40:14 volumio volumio[835]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 09:40:15 volumio sudo[26859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-04 09:39 Sep 04 09:40:15 volumio sudo[26859]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"