-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Wed 2025-06-25 20:05:22 BST. -- Jun 25 20:04:01 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:03 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Jun 25 20:04:03 volumio volumio[955]: info: CoreStateMachine::play index undefined Jun 25 20:04:03 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 25 20:04:03 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:03 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Jun 25 20:04:03 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Jun 25 20:04:03 volumio volumio[955]: info: sendMpdCommand stop took 31 milliseconds Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand clear Jun 25 20:04:03 volumio volumio[955]: info: sendMpdCommand clear took 15 milliseconds Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 25 20:04:03 volumio volumio[955]: info: Jun 25 20:04:03 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 25 20:04:03 volumio volumio[955]: info: Ignoring MPD Status Update Jun 25 20:04:03 volumio volumio[955]: info: Jun 25 20:04:03 volumio volumio[955]: ---------------------------- MPD announces state update: options Jun 25 20:04:03 volumio volumio[955]: info: sendMpdCommand consume 1 took 4 milliseconds Jun 25 20:04:03 volumio volumio[955]: info: ControllerMpd::getState Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 25 20:04:03 volumio volumio[955]: info: [1750878243657] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Jun 25 20:04:03 volumio volumio[955]: info: Jun 25 20:04:03 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 25 20:04:03 volumio volumio[955]: info: Ignoring MPD Status Update Jun 25 20:04:03 volumio volumio[955]: info: Jun 25 20:04:03 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 25 20:04:03 volumio volumio[955]: info: Ignoring MPD Status Update Jun 25 20:04:03 volumio volumio[955]: error: updateQueue error: null Jun 25 20:04:03 volumio volumio[955]: info: ------------------------------ 14ms Jun 25 20:04:03 volumio volumio[955]: info: sendMpdCommand status took 10 milliseconds Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 25 20:04:03 volumio volumio[955]: info: ControllerMpd::pushState Jun 25 20:04:03 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 25 20:04:03 volumio volumio[955]: info: CoreStateMachine::pushState Jun 25 20:04:03 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:03 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Jun 25 20:04:03 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:03 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 25 20:04:03 volumio volumio[955]: info: ------------------------------ 45ms Jun 25 20:04:03 volumio volumio[955]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 44 milliseconds Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand play Jun 25 20:04:03 volumio volumio[955]: info: Jun 25 20:04:03 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 25 20:04:03 volumio volumio[955]: info: Ignoring MPD Status Update Jun 25 20:04:03 volumio volumio[955]: info: ------------------------------ 136ms Jun 25 20:04:03 volumio volumio[955]: info: ------------------------------ 134ms Jun 25 20:04:03 volumio volumio[955]: error: Upnp client error: Error: This socket has been ended by the other party Jun 25 20:04:03 volumio volumio[955]: info: Jun 25 20:04:03 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 25 20:04:03 volumio volumio[955]: info: Ignoring MPD Status Update Jun 25 20:04:03 volumio volumio[955]: info: Jun 25 20:04:03 volumio volumio[955]: ---------------------------- MPD announces state update: options Jun 25 20:04:03 volumio volumio[955]: info: ControllerMpd::getState Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 25 20:04:03 volumio volumio[955]: info: Jun 25 20:04:03 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 25 20:04:03 volumio volumio[955]: info: Ignoring MPD Status Update Jun 25 20:04:03 volumio volumio[955]: info: Jun 25 20:04:03 volumio volumio[955]: ---------------------------- MPD announces state update: options Jun 25 20:04:03 volumio volumio[955]: info: ControllerMpd::getState Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 25 20:04:03 volumio volumio[955]: info: sendMpdCommand play took 115 milliseconds Jun 25 20:04:03 volumio volumio[955]: info: ------------------------------ 103ms Jun 25 20:04:03 volumio volumio[955]: info: ------------------------------ 13ms Jun 25 20:04:03 volumio volumio[955]: info: sendMpdCommand status took 11 milliseconds Jun 25 20:04:03 volumio volumio[955]: info: ------------------------------ 10ms Jun 25 20:04:03 volumio volumio[955]: info: [1750878243835] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 20:04:03 volumio volumio[955]: info: sendMpdCommand status took 20 milliseconds Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 20:04:03 volumio volumio[955]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 25 20:04:03 volumio volumio[955]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 25 20:04:03 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 25 20:04:03 volumio volumio[955]: info: ControllerMpd::pushState Jun 25 20:04:03 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 25 20:04:03 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:03 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 25 20:04:03 volumio volumio[955]: info: ControllerMpd::pushState Jun 25 20:04:03 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 25 20:04:03 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:03 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 25 20:04:03 volumio volumio[955]: info: ------------------------------ 43ms Jun 25 20:04:03 volumio volumio[955]: info: ------------------------------ 39ms Jun 25 20:04:04 volumio volumio[955]: info: [1750878244625] [RadioParadise] received new metadata: {"time":191,"artist":"Buffy Sainte-Marie","title":"God Is Alive, Magic Is Afoot","album":"Up Where We Belong","year":"1970","cover":"https://img.radioparadise.com/covers/l/19137_7a4edada-5ba1-4167-bfdd-c6395e2716fd.jpg","cover_med":"https://img.radioparadise.com/covers/m/19137_7a4edada-5ba1-4167-bfdd-c6395e2716fd.jpg","cover_small":"https://img.radioparadise.com/covers/s/19137_7a4edada-5ba1-4167-bfdd-c6395e2716fd.jpg"} Jun 25 20:04:04 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:04 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 25 20:04:04 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:04 volumio volumio[955]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19137_7a4edada-5ba1-4167-bfdd-c6395e2716fd.jpg","uri":"https://stream.radioparadise.com/flac","name":"God Is Alive, Magic Is Afoot","title":"God Is Alive, Magic Is Afoot","artist":"Buffy Sainte-Marie","album":"Up Where We Belong","streaming":true,"disableUiControls":true,"duration":196,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jun 25 20:04:04 volumio volumio[955]: verbose: CURRENT POSITION 0 Jun 25 20:04:04 volumio volumio[955]: info: CoreStateMachine::syncState stateService play Jun 25 20:04:04 volumio volumio[955]: info: CoreStateMachine::syncState currentStatus stop Jun 25 20:04:04 volumio volumio[955]: info: [1750878244634] [RadioParadise] setting new timer with duration of 196000 seconds. Jun 25 20:04:06 volumio volumio[955]: info: Jun 25 20:04:06 volumio volumio[955]: ---------------------------- MPD announces state update: player Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::getState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 25 20:04:06 volumio volumio[955]: info: Jun 25 20:04:06 volumio volumio[955]: ---------------------------- MPD announces state update: player Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::getState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 25 20:04:06 volumio volumio[955]: info: Jun 25 20:04:06 volumio volumio[955]: ---------------------------- MPD announces state update: player Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::getState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand status took 64 milliseconds Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 20:04:06 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Jun 25 20:04:06 volumio volumio[955]: info: Jun 25 20:04:06 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 25 20:04:06 volumio volumio[955]: info: Ignoring MPD Status Update Jun 25 20:04:06 volumio volumio[955]: info: Jun 25 20:04:06 volumio volumio[955]: ---------------------------- MPD announces state update: player Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::getState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand status took 68 milliseconds Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand status took 58 milliseconds Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::pushState Jun 25 20:04:06 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 25 20:04:06 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:06 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 25 20:04:06 volumio volumio[955]: info: ------------------------------ 84ms Jun 25 20:04:06 volumio volumio[955]: info: Jun 25 20:04:06 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 25 20:04:06 volumio volumio[955]: info: Ignoring MPD Status Update Jun 25 20:04:06 volumio volumio[955]: info: Jun 25 20:04:06 volumio volumio[955]: ---------------------------- MPD announces state update: player Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::getState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 25 20:04:06 volumio volumio[955]: info: Jun 25 20:04:06 volumio volumio[955]: ---------------------------- MPD announces system playlist update Jun 25 20:04:06 volumio volumio[955]: info: Ignoring MPD Status Update Jun 25 20:04:06 volumio volumio[955]: info: Jun 25 20:04:06 volumio volumio[955]: ---------------------------- MPD announces state update: player Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::getState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand status Jun 25 20:04:06 volumio volumio[955]: info: ------------------------------ 38ms Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand status took 35 milliseconds Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand playlistinfo took 32 milliseconds Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 25 20:04:06 volumio volumio[955]: info: ------------------------------ 27ms Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand status took 26 milliseconds Jun 25 20:04:06 volumio volumio[955]: info: ------------------------------ 24ms Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand status took 17 milliseconds Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseState Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::pushState Jun 25 20:04:06 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 25 20:04:06 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:06 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::pushState Jun 25 20:04:06 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 25 20:04:06 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:06 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 25 20:04:06 volumio volumio[955]: info: ------------------------------ 126ms Jun 25 20:04:06 volumio volumio[955]: info: ------------------------------ 116ms Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 25 20:04:06 volumio volumio[955]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 25 20:04:06 volumio volumio[955]: verbose: ControllerMpd::parseTrackInfo Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::pushState Jun 25 20:04:06 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 25 20:04:06 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:06 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::pushState Jun 25 20:04:06 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 25 20:04:06 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:06 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 25 20:04:06 volumio volumio[955]: info: ControllerMpd::pushState Jun 25 20:04:06 volumio volumio[955]: info: CoreCommandRouter::servicePushState Jun 25 20:04:06 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:06 volumio volumio[955]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Jun 25 20:04:06 volumio volumio[955]: info: ------------------------------ 71ms Jun 25 20:04:06 volumio volumio[955]: info: ------------------------------ 56ms Jun 25 20:04:06 volumio volumio[955]: info: ------------------------------ 50ms Jun 25 20:04:06 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:08 volumio volumio-remote-updater[565]: curl_easy_perform() failed: Timeout was reached Jun 25 20:04:08 volumio volumio-remote-updater[565]: terminate called after throwing an instance of 'std::invalid_argument' Jun 25 20:04:08 volumio volumio-remote-updater[565]: what(): parse error - unexpected end of input Jun 25 20:04:08 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Jun 25 20:04:08 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Jun 25 20:04:08 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Jun 25 20:04:08 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Jun 25 20:04:08 volumio systemd[1]: Stopped volumio-remote-updater.service. Jun 25 20:04:08 volumio systemd[1]: Started volumio-remote-updater.service. Jun 25 20:04:09 volumio volumio-remote-updater[1713]: Error: No active session Jun 25 20:04:09 volumio volumio-remote-updater[1713]: [2025-06-25 20:04:09] [connect] Successful connection Jun 25 20:04:09 volumio volumio-remote-updater[1713]: [2025-06-25 20:04:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750878249 101 Jun 25 20:04:09 volumio volumio[955]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 10 Jun 25 20:04:12 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 20:04:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 20:04:17 volumio volumio[955]: info: Discovery: Getting this device information Jun 25 20:04:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:04:17 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 20:04:17 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.1.194:3000 from 192.168.1.95 UA: Dart/3.5 (dart:io) Total Clients: 7 Jun 25 20:04:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:04:17 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 25 20:04:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 25 20:04:18 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 20:04:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 20:04:18 volumio volumio[955]: info: Discovery: Getting this device information Jun 25 20:04:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:04:18 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 20:04:20 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:04:20 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:21 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.1.194:3000 from 192.168.1.95 UA: Dart/3.5 (dart:io) Total Clients: 7 Jun 25 20:04:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:04:21 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:21 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 25 20:04:21 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 25 20:04:21 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:04:21 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:22 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 20:04:22 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 20:04:22 volumio volumio[955]: info: Discovery: Getting this device information Jun 25 20:04:22 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:04:22 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:22 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 20:04:23 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:23 volumio sudo[1751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 25 20:04:23 volumio sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 25 20:04:23 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 20:04:23 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 20:04:23 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root Jun 25 20:04:23 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Jun 25 20:04:23 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.1.194 from 192.168.1.95 UA: Mozilla/5.0 (Linux; Android 11; SM-A505FN Build/RP1A.200720.012; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Total Clients: 8 Jun 25 20:04:24 volumio sudo[1757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 25 20:04:24 volumio sudo[1757]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 20:04:24 volumio sudo[1757]: pam_unix(sudo:session): session closed for user root Jun 25 20:04:24 volumio sudo[1759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 25 20:04:24 volumio sudo[1759]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 25 20:04:24 volumio sudo[1759]: pam_unix(sudo:session): session closed for user root Jun 25 20:04:24 volumio volumio[955]: verbose: New Socket.io Connection to 192.168.1.194 from 192.168.1.95 UA: Mozilla/5.0 (Linux; Android 11; SM-A505FN Build/RP1A.200720.012; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Total Clients: 9 Jun 25 20:04:25 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:04:25 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:25 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 25 20:04:25 volumio volumio[955]: info: Listing playlists Jun 25 20:04:25 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 25 20:04:25 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 25 20:04:25 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 25 20:04:25 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 25 20:04:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 20:04:26 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 25 20:04:28 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 20:04:29 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:30 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 20:04:30 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 25 20:04:30 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 25 20:04:30 volumio volumio[955]: info: Retrieving Cloud Streaming UI Jun 25 20:04:30 volumio volumio[955]: info: Getting Tidal Cloud Configuration Jun 25 20:04:30 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 25 20:04:30 volumio volumio[955]: info: Getting Qobuz Cloud Configuration Jun 25 20:04:30 volumio volumio[955]: info: Asking plugin for UI Config Jun 25 20:04:30 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 25 20:04:30 volumio volumio[955]: info: Getting Spotify Cloud Configuration Jun 25 20:04:30 volumio volumio[955]: info: Asking plugin for UI Config Jun 25 20:04:30 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 25 20:04:30 volumio volumio[955]: info: Saving Spotify Acccount Jun 25 20:04:30 volumio volumio[955]: info: Got Tidal Cloud Configuration Jun 25 20:04:30 volumio volumio[955]: info: Got it Jun 25 20:04:30 volumio volumio[955]: info: Got it Jun 25 20:04:30 volumio volumio[955]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 25 20:04:30 volumio volumio[955]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 25 20:04:30 volumio volumio[955]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetBrowseSources Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetBrowseSources Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::volumioGetBrowseSources Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 25 20:04:31 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 25 20:04:34 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:35 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:04:39 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:04:40 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:44 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:04:44 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 25 20:04:44 volumio volumio[955]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 25 20:04:44 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 25 20:04:44 volumio volumio[955]: info: Received Get System Version Jun 25 20:04:44 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 25 20:04:44 volumio volumio[955]: info: Received Get System Info Jun 25 20:04:44 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 20:04:44 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 20:04:44 volumio volumio[955]: info: Discovery: Getting this device information Jun 25 20:04:44 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:04:44 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:44 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 20:04:46 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 20:04:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 20:04:47 volumio volumio[955]: info: Discovery: Getting this device information Jun 25 20:04:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:04:47 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:04:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 20:04:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:04:51 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:51 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:04:55 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:04:57 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:04:59 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:05:02 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:05:03 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:05:07 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:05:08 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:05:11 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:05:14 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:05:15 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:05:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 25 20:05:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 25 20:05:17 volumio volumio[955]: info: Discovery: Getting this device information Jun 25 20:05:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Jun 25 20:05:17 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Jun 25 20:05:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 25 20:05:19 volumio wpa_supplicant[798]: wlan0: Failed to initiate sched scan Jun 25 20:05:19 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 25 20:05:20 volumio volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 25 20:05:20 volumio volumio[955]: Error: connect ETIMEDOUT 199.232.54.79:80 Jun 25 20:05:20 volumio volumio[955]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 25 20:05:20 volumio volumio[955]: errno: -110, Jun 25 20:05:20 volumio volumio[955]: code: 'ETIMEDOUT', Jun 25 20:05:20 volumio volumio[955]: syscall: 'connect', Jun 25 20:05:20 volumio volumio[955]: address: '199.232.54.79', Jun 25 20:05:20 volumio volumio[955]: port: 80 Jun 25 20:05:20 volumio volumio[955]: } Jun 25 20:05:20 volumio volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 25 20:05:22 volumio sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-25 20:04 Jun 25 20:05:22 volumio sudo[1882]: 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="f8baf7ad070f376535a93fcc6590774528ddb153" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 30 Jan 2024 03:58:37 PM CET" VOLUMIO_VERSION="3.611" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1d6379ebb89c40061afa8aee78176887"