Oct 14 15:00:53 volumio-3b volumio[1238]: info: Clearing queue after UPNP request Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::ClearQueue Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::stop Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::stPlaybackTimer Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::updateTrackBlock Oct 14 15:00:53 volumio-3b volumio[1238]: info: CorePlayQueue::getTrackBlock Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: CorePlayQueue::getTrack 48 Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::serviceStop Oct 14 15:00:53 volumio-3b volumio[1238]: info: CorePlayQueue::getTrack 48 Oct 14 15:00:53 volumio-3b volumio[1238]: info: ControllerMpd::stop Oct 14 15:00:53 volumio-3b volumio[1238]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 15:00:53 volumio-3b volumio[1238]: info: CorePlayQueue::clearPlayQueue Oct 14 15:00:53 volumio-3b volumio[1238]: info: CorePlayQueue::saveQueue Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushQueue Oct 14 15:00:53 volumio-3b volumio[1238]: info: Oct 14 15:00:53 volumio-3b volumio[1238]: ---------------------------- MPD announces state update: player Oct 14 15:00:53 volumio-3b volumio[1238]: info: sendMpdCommand stop took 62 milliseconds Oct 14 15:00:53 volumio-3b volumio[1238]: info: ControllerMpd::getState Oct 14 15:00:53 volumio-3b volumio[1238]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:00:53 volumio-3b volumio[1238]: info: sendMpdCommand status took 14 milliseconds Oct 14 15:00:53 volumio-3b volumio[1238]: verbose: ControllerMpd::parseState Oct 14 15:00:53 volumio-3b volumio[1238]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 15:00:53 volumio-3b volumio[1238]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 15:00:53 volumio-3b volumio[1238]: verbose: ControllerMpd::parseTrackInfo Oct 14 15:00:53 volumio-3b volumio[1238]: info: ControllerMpd::pushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreCommandRouter::servicePushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: CorePlayQueue::getTrack 48 Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: CorePlayQueue::getTrack 48 Oct 14 15:00:53 volumio-3b volumio[1238]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"被遗忘的时光-《无间道》电影插曲|《摆渡人》电影插曲","artist":"蔡琴","album":"民歌蔡琴","uri":"http://192.168.4.22:55599/content/android/55.flac","trackType":"flac"} Oct 14 15:00:53 volumio-3b volumio[1238]: verbose: CURRENT POSITION 48 Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::syncState stateService stop Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::syncState currentStatus stop Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: CorePlayQueue::getTrack 48 Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: No code Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: CorePlayQueue::getTrack 48 Oct 14 15:00:53 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:00:53 volumio-3b volumio[1238]: info: ------------------------------ 49ms Oct 14 15:00:53 volumio-3b volumio[1238]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 14 15:00:55 volumio-3b volumio[1238]: info: Starting UPNP Playback Oct 14 15:00:55 volumio-3b volumio[1238]: info: Preparing playback through UPNP Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetState Oct 14 15:00:55 volumio-3b volumio[1238]: info: CorePlayQueue::getTrack 48 Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 15:00:55 volumio-3b volumio[1238]: info: Oct 14 15:00:55 volumio-3b volumio[1238]: ---------------------------- MPD announces system playlist update Oct 14 15:00:55 volumio-3b volumio[1238]: info: Ignoring MPD Status Update Oct 14 15:00:55 volumio-3b volumio[1238]: info: ------------------------------ 1ms Oct 14 15:00:55 volumio-3b volumio[1238]: info: Oct 14 15:00:55 volumio-3b volumio[1238]: ---------------------------- MPD announces system playlist update Oct 14 15:00:55 volumio-3b volumio[1238]: info: Ignoring MPD Status Update Oct 14 15:00:55 volumio-3b volumio[1238]: info: ------------------------------ 2ms Oct 14 15:00:55 volumio-3b volumio[1238]: info: Oct 14 15:00:55 volumio-3b volumio[1238]: ---------------------------- MPD announces system playlist update Oct 14 15:00:55 volumio-3b volumio[1238]: info: Ignoring MPD Status Update Oct 14 15:00:55 volumio-3b volumio[1238]: info: Oct 14 15:00:55 volumio-3b volumio[1238]: ---------------------------- MPD announces system playlist update Oct 14 15:00:55 volumio-3b volumio[1238]: info: Ignoring MPD Status Update Oct 14 15:00:55 volumio-3b volumio[1238]: info: ------------------------------ 3ms Oct 14 15:00:55 volumio-3b volumio[1238]: info: ------------------------------ 2ms Oct 14 15:00:55 volumio-3b volumio[1238]: info: Oct 14 15:00:55 volumio-3b volumio[1238]: ---------------------------- MPD announces system playlist update Oct 14 15:00:55 volumio-3b volumio[1238]: info: Ignoring MPD Status Update Oct 14 15:00:55 volumio-3b volumio[1238]: info: Oct 14 15:00:55 volumio-3b volumio[1238]: ---------------------------- MPD announces system playlist update Oct 14 15:00:55 volumio-3b volumio[1238]: info: Ignoring MPD Status Update Oct 14 15:00:55 volumio-3b volumio[1238]: info: ------------------------------ 2ms Oct 14 15:00:55 volumio-3b volumio[1238]: info: ------------------------------ 2ms Oct 14 15:00:55 volumio-3b volumio[1238]: info: Oct 14 15:00:55 volumio-3b volumio[1238]: ---------------------------- MPD announces state update: player Oct 14 15:00:55 volumio-3b volumio[1238]: info: ControllerMpd::getState Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:00:55 volumio-3b volumio[1238]: info: Oct 14 15:00:55 volumio-3b volumio[1238]: ---------------------------- MPD announces state update: player Oct 14 15:00:55 volumio-3b volumio[1238]: info: sendMpdCommand status took 21 milliseconds Oct 14 15:00:55 volumio-3b volumio[1238]: info: ControllerMpd::getState Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::parseState Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 15:00:55 volumio-3b volumio[1238]: info: sendMpdCommand status took 2 milliseconds Oct 14 15:00:55 volumio-3b volumio[1238]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::parseState Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::parseTrackInfo Oct 14 15:00:55 volumio-3b volumio[1238]: info: ControllerMpd::pushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::servicePushState Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: In UPNP mode Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Falling In The Moonlight","artist":"Kenny G","album":"刻骨铭心","uri":"http://192.168.4.22:55599/content/android/84.flac","trackType":"flac"} Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: CURRENT POSITION 48 Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::syncState stateService play Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::syncState currentStatus stop Oct 14 15:00:55 volumio-3b volumio[1238]: info: ------------------------------ 25ms Oct 14 15:00:55 volumio-3b volumio[1238]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::parseTrackInfo Oct 14 15:00:55 volumio-3b volumio[1238]: info: ControllerMpd::pushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::servicePushState Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: In UPNP mode Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Falling In The Moonlight","artist":"Kenny G","album":"刻骨铭心","uri":"http://192.168.4.22:55599/content/android/84.flac","trackType":"flac"} Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: CURRENT POSITION 48 Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::syncState stateService play Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::syncState currentStatus play Oct 14 15:00:55 volumio-3b volumio[1238]: info: Received an update from plugin. extracting info from payload Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: ------------------------------ 23ms Oct 14 15:00:55 volumio-3b volumio[1238]: info: Oct 14 15:00:55 volumio-3b volumio[1238]: ---------------------------- MPD announces system playlist update Oct 14 15:00:55 volumio-3b volumio[1238]: info: Ignoring MPD Status Update Oct 14 15:00:55 volumio-3b volumio[1238]: info: Oct 14 15:00:55 volumio-3b volumio[1238]: ---------------------------- MPD announces state update: player Oct 14 15:00:55 volumio-3b volumio[1238]: info: ControllerMpd::getState Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::sendMpdCommand status Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: ------------------------------ 9ms Oct 14 15:00:55 volumio-3b volumio[1238]: info: sendMpdCommand status took 8 milliseconds Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::parseState Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 15:00:55 volumio-3b volumio[1238]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: ControllerMpd::parseTrackInfo Oct 14 15:00:55 volumio-3b volumio[1238]: info: ControllerMpd::pushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::servicePushState Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: In UPNP mode Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":318,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"656 Kbps","isStreaming":false,"title":"Falling In The Moonlight","artist":"Kenny G","album":"刻骨铭心","uri":"http://192.168.4.22:55599/content/android/84.flac","trackType":"flac"} Oct 14 15:00:55 volumio-3b volumio[1238]: verbose: CURRENT POSITION 48 Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::syncState stateService play Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::syncState currentStatus play Oct 14 15:00:55 volumio-3b volumio[1238]: info: Received an update from plugin. extracting info from payload Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:00:55 volumio-3b volumio[1238]: info: ------------------------------ 45ms Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:00:55 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:16 volumio-3b upmpdcli[13663]: 74 Oct 14 15:01:16 volumio-3b upmpdcli[13665]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:16 volumio-3b upmpdcli[13665]: Dload Upload Total Spent Left Speed Oct 14 15:01:16 volumio-3b volumio[1238]: info: Oct 14 15:01:16 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 74 Oct 14 15:01:16 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume74 Oct 14 15:01:16 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:16 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:16 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:16 volumio-3b upmpdcli[13665]: [158B blob data] Oct 14 15:01:16 volumio-3b upmpdcli[13665]: {"time":1791961276848,"response":"volume Success"} Oct 14 15:01:16 volumio-3b upmpdcli[13663]: 74 Oct 14 15:01:16 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:16 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:16 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:18 volumio-3b upmpdcli[13700]: 73 Oct 14 15:01:18 volumio-3b upmpdcli[13702]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:18 volumio-3b upmpdcli[13702]: Dload Upload Total Spent Left Speed Oct 14 15:01:18 volumio-3b volumio[1238]: info: Oct 14 15:01:18 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 73 Oct 14 15:01:18 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume73 Oct 14 15:01:18 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:18 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:18 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:18 volumio-3b upmpdcli[13702]: [158B blob data] Oct 14 15:01:18 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:18 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:18 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:18 volumio-3b upmpdcli[13702]: {"time":1791961278290,"response":"volume Success"} Oct 14 15:01:18 volumio-3b upmpdcli[13700]: 73 Oct 14 15:01:20 volumio-3b upmpdcli[13723]: 72 Oct 14 15:01:20 volumio-3b upmpdcli[13725]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:20 volumio-3b upmpdcli[13725]: Dload Upload Total Spent Left Speed Oct 14 15:01:20 volumio-3b volumio[1238]: info: Oct 14 15:01:20 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 72 Oct 14 15:01:20 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume72 Oct 14 15:01:20 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:20 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:20 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:20 volumio-3b upmpdcli[13725]: [158B blob data] Oct 14 15:01:20 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:20 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:20 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:20 volumio-3b upmpdcli[13725]: {"time":1791961280297,"response":"volume Success"} Oct 14 15:01:20 volumio-3b upmpdcli[13723]: 72 Oct 14 15:01:20 volumio-3b upmpdcli[13731]: 71 Oct 14 15:01:20 volumio-3b upmpdcli[13733]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:20 volumio-3b upmpdcli[13733]: Dload Upload Total Spent Left Speed Oct 14 15:01:20 volumio-3b volumio[1238]: info: Oct 14 15:01:20 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 71 Oct 14 15:01:20 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume71 Oct 14 15:01:20 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:20 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:20 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:20 volumio-3b upmpdcli[13733]: [158B blob data] Oct 14 15:01:20 volumio-3b upmpdcli[13733]: {"time":1791961280660,"response":"volume Success"} Oct 14 15:01:20 volumio-3b upmpdcli[13731]: 71 Oct 14 15:01:20 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:20 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:20 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:21 volumio-3b upmpdcli[13740]: 70 Oct 14 15:01:21 volumio-3b upmpdcli[13742]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:21 volumio-3b upmpdcli[13742]: Dload Upload Total Spent Left Speed Oct 14 15:01:21 volumio-3b volumio[1238]: info: Oct 14 15:01:21 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 70 Oct 14 15:01:21 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume70 Oct 14 15:01:21 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:21 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:21 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:21 volumio-3b upmpdcli[13742]: [158B blob data] Oct 14 15:01:21 volumio-3b upmpdcli[13742]: {"time":1791961281119,"response":"volume Success"} Oct 14 15:01:21 volumio-3b upmpdcli[13740]: 70 Oct 14 15:01:21 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:21 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:21 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:21 volumio-3b upmpdcli[13753]: 69 Oct 14 15:01:21 volumio-3b upmpdcli[13755]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:21 volumio-3b upmpdcli[13755]: Dload Upload Total Spent Left Speed Oct 14 15:01:21 volumio-3b volumio[1238]: info: Oct 14 15:01:21 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 69 Oct 14 15:01:21 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume69 Oct 14 15:01:21 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:21 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:21 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:21 volumio-3b upmpdcli[13755]: [158B blob data] Oct 14 15:01:21 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:21 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:21 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:21 volumio-3b upmpdcli[13755]: {"time":1791961281500,"response":"volume Success"} Oct 14 15:01:21 volumio-3b upmpdcli[13753]: 69 Oct 14 15:01:22 volumio-3b upmpdcli[13768]: 68 Oct 14 15:01:22 volumio-3b upmpdcli[13771]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:22 volumio-3b upmpdcli[13771]: Dload Upload Total Spent Left Speed Oct 14 15:01:22 volumio-3b volumio[1238]: info: Oct 14 15:01:22 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 68 Oct 14 15:01:22 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume68 Oct 14 15:01:22 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:22 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:22 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:22 volumio-3b upmpdcli[13771]: [158B blob data] Oct 14 15:01:22 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:22 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:22 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:22 volumio-3b upmpdcli[13771]: {"time":1791961282836,"response":"volume Success"} Oct 14 15:01:22 volumio-3b upmpdcli[13768]: 68 Oct 14 15:01:23 volumio-3b upmpdcli[13777]: 67 Oct 14 15:01:23 volumio-3b upmpdcli[13779]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:23 volumio-3b upmpdcli[13779]: Dload Upload Total Spent Left Speed Oct 14 15:01:23 volumio-3b volumio[1238]: info: Oct 14 15:01:23 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 67 Oct 14 15:01:23 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume67 Oct 14 15:01:23 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:23 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:23 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:23 volumio-3b upmpdcli[13779]: [158B blob data] Oct 14 15:01:23 volumio-3b upmpdcli[13779]: {"time":1791961283194,"response":"volume Success"} Oct 14 15:01:23 volumio-3b upmpdcli[13777]: 67 Oct 14 15:01:23 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:23 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:23 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:24 volumio-3b upmpdcli[13799]: 66 Oct 14 15:01:24 volumio-3b upmpdcli[13802]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:24 volumio-3b upmpdcli[13802]: Dload Upload Total Spent Left Speed Oct 14 15:01:24 volumio-3b volumio[1238]: info: Oct 14 15:01:24 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 66 Oct 14 15:01:24 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume66 Oct 14 15:01:24 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:24 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:24 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:24 volumio-3b upmpdcli[13802]: [158B blob data] Oct 14 15:01:24 volumio-3b upmpdcli[13802]: {"time":1791961284867,"response":"volume Success"} Oct 14 15:01:24 volumio-3b upmpdcli[13799]: 66 Oct 14 15:01:24 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:24 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:24 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:27 volumio-3b upmpdcli[13839]: 67 Oct 14 15:01:27 volumio-3b upmpdcli[13841]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:27 volumio-3b upmpdcli[13841]: Dload Upload Total Spent Left Speed Oct 14 15:01:27 volumio-3b volumio[1238]: info: Oct 14 15:01:27 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 67 Oct 14 15:01:27 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume67 Oct 14 15:01:27 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:27 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:27 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:27 volumio-3b upmpdcli[13841]: [158B blob data] Oct 14 15:01:27 volumio-3b upmpdcli[13841]: {"time":1791961287120,"response":"volume Success"} Oct 14 15:01:27 volumio-3b upmpdcli[13839]: 67 Oct 14 15:01:27 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:27 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:27 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:28 volumio-3b upmpdcli[13854]: 68 Oct 14 15:01:28 volumio-3b upmpdcli[13856]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:28 volumio-3b upmpdcli[13856]: Dload Upload Total Spent Left Speed Oct 14 15:01:28 volumio-3b volumio[1238]: info: Oct 14 15:01:28 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 68 Oct 14 15:01:28 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume68 Oct 14 15:01:28 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:28 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:28 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:28 volumio-3b upmpdcli[13856]: [158B blob data] Oct 14 15:01:28 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:28 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:28 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:28 volumio-3b upmpdcli[13856]: {"time":1791961288122,"response":"volume Success"} Oct 14 15:01:28 volumio-3b upmpdcli[13854]: 68 Oct 14 15:01:29 volumio-3b upmpdcli[13869]: 69 Oct 14 15:01:29 volumio-3b upmpdcli[13871]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:29 volumio-3b upmpdcli[13871]: Dload Upload Total Spent Left Speed Oct 14 15:01:29 volumio-3b volumio[1238]: info: Oct 14 15:01:29 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 69 Oct 14 15:01:29 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume69 Oct 14 15:01:29 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:29 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:29 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:29 volumio-3b upmpdcli[13871]: [158B blob data] Oct 14 15:01:29 volumio-3b upmpdcli[13871]: {"time":1791961289355,"response":"volume Success"} Oct 14 15:01:29 volumio-3b upmpdcli[13869]: 69 Oct 14 15:01:29 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:29 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:29 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:30 volumio-3b upmpdcli[13891]: 70 Oct 14 15:01:30 volumio-3b upmpdcli[13893]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 14 15:01:30 volumio-3b upmpdcli[13893]: Dload Upload Total Spent Left Speed Oct 14 15:01:30 volumio-3b volumio[1238]: info: Oct 14 15:01:30 volumio-3b volumio[1238]: ---------------------------- Client requests Volume 70 Oct 14 15:01:30 volumio-3b volumio[1238]: info: VolumeController::SetAlsaVolume70 Oct 14 15:01:30 volumio-3b volumio[1238]: info: CoreStateMachine::pushState Oct 14 15:01:30 volumio-3b volumio[1238]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 15:01:30 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioPushState Oct 14 15:01:30 volumio-3b upmpdcli[13893]: [158B blob data] Oct 14 15:01:30 volumio-3b upmpdcli[13893]: {"time":1791961290513,"response":"volume Success"} Oct 14 15:01:30 volumio-3b upmpdcli[13891]: 70 Oct 14 15:01:30 volumio-3b volumio[1238]: info: CoreCommandRouter::volumioGetQueue Oct 14 15:01:30 volumio-3b volumio[1238]: info: CoreStateMachine::getQueue Oct 14 15:01:30 volumio-3b volumio[1238]: info: CorePlayQueue::getQueue Oct 14 15:01:45 volumio-3b volumio[1238]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 15:01:45 volumio-3b volumio[1238]: Error: connect ETIMEDOUT 108.160.162.109:443 Oct 14 15:01:45 volumio-3b volumio[1238]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 14 15:01:45 volumio-3b volumio[1238]: errno: -110, Oct 14 15:01:45 volumio-3b volumio[1238]: code: 'ETIMEDOUT', Oct 14 15:01:45 volumio-3b volumio[1238]: syscall: 'connect', Oct 14 15:01:45 volumio-3b volumio[1238]: address: '108.160.162.109', Oct 14 15:01:45 volumio-3b volumio[1238]: port: 443 Oct 14 15:01:45 volumio-3b volumio[1238]: } Oct 14 15:01:45 volumio-3b volumio[1238]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 15:01:48 volumio-3b sudo[14099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 15:00' Oct 14 15:01:48 volumio-3b sudo[14099]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"