Dec 28 18:16:05 volumio volumio[1398]: info: Preload queue cleared Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::ClearQueue Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::stop Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::stPlaybackTimer Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::updateTrackBlock Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrackBlock Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::pushState Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Dec 28 18:16:05 volumio volumio[1398]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:16:05 volumio volumio[1398]: info: MRS: Pushing multiroomSync output Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::serviceStop Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::serviceStop Dec 28 18:16:05 volumio volumio[1398]: info: [1766916965073] ControllerWebradio::stop Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::clearPlayQueue Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::saveQueue Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::addQueueItems Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::addQueueItems Dec 28 18:16:05 volumio volumio[1398]: info: Preload queue cleared Dec 28 18:16:05 volumio volumio[1398]: info: Adding Item to queue: http://59.120.88.155:8000/live.mp3 Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::saveQueue Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::updateTrackBlock Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrackBlock Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioPlay Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::play index 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::stop Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::play index undefined Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::startPlaybackTimer Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 18:16:05 volumio volumio[1398]: info: [1766916965096] ControllerWebradio::clearAddPlayTrack Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 18:16:05 volumio volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Dec 28 18:16:05 volumio volumio[1398]: info: Dec 28 18:16:05 volumio volumio[1398]: ---------------------------- MPD announces state update: player Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand stop took 38 milliseconds Dec 28 18:16:05 volumio volumio[1398]: info: ControllerMpd::getState Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand stop took 15 milliseconds Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 18:16:05 volumio volumio[1398]: info: Dec 28 18:16:05 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Dec 28 18:16:05 volumio volumio[1398]: info: Ignoring MPD Status Update Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand clear took 1 milliseconds Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::parseState Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand load "http://59.120.88.155:8000/live.mp3" Dec 28 18:16:05 volumio volumio[1398]: error: updateQueue error: null Dec 28 18:16:05 volumio volumio[1398]: info: ------------------------------ 8ms Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Dec 28 18:16:05 volumio volumio[1398]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 28 18:16:05 volumio volumio[1398]: info: ------------------------------ 10ms Dec 28 18:16:05 volumio volumio[1398]: info: touch_display: Setting screensaver timeout to 300 seconds. Dec 28 18:16:05 volumio volumio[1398]: info: Preload queue cleared Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::ClearQueue Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::stop Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::clearPlayQueue Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::saveQueue Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::addQueueItems Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::addQueueItems Dec 28 18:16:05 volumio volumio[1398]: info: Preload queue cleared Dec 28 18:16:05 volumio volumio[1398]: info: Adding Item to queue: http://59.120.88.155:8000/live.mp3 Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::saveQueue Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::updateTrackBlock Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrackBlock Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioPlay Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::play index 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::stop Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::play index undefined Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::startPlaybackTimer Dec 28 18:16:05 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 18:16:05 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 18:16:05 volumio volumio[1398]: info: [1766916965287] ControllerWebradio::clearAddPlayTrack Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand add "http://59.120.88.155:8000/live.mp3" Dec 28 18:16:05 volumio volumio[1398]: info: Dec 28 18:16:05 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Dec 28 18:16:05 volumio volumio[1398]: info: Ignoring MPD Status Update Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand stop took 17 milliseconds Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand add "http://59.120.88.155:8000/live.mp3" took 1 milliseconds Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand play Dec 28 18:16:05 volumio volumio[1398]: info: Dec 28 18:16:05 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Dec 28 18:16:05 volumio volumio[1398]: info: Ignoring MPD Status Update Dec 28 18:16:05 volumio volumio[1398]: info: ------------------------------ 1ms Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand clear took 0 milliseconds Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand play took 0 milliseconds Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand load "http://59.120.88.155:8000/live.mp3" Dec 28 18:16:05 volumio volumio[1398]: error: updateQueue error: null Dec 28 18:16:05 volumio volumio[1398]: info: ------------------------------ 1ms Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand add "http://59.120.88.155:8000/live.mp3" Dec 28 18:16:05 volumio volumio[1398]: info: Dec 28 18:16:05 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Dec 28 18:16:05 volumio volumio[1398]: info: Ignoring MPD Status Update Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand add "http://59.120.88.155:8000/live.mp3" took 0 milliseconds Dec 28 18:16:05 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand play Dec 28 18:16:05 volumio volumio[1398]: info: ------------------------------ 1ms Dec 28 18:16:05 volumio volumio[1398]: info: sendMpdCommand play took 1 milliseconds Dec 28 18:16:05 volumio volumio[1398]: info: Dec 28 18:16:05 volumio volumio[1398]: ---------------------------- MPD announces state update: player Dec 28 18:16:05 volumio volumio[1398]: info: ControllerMpd::getState Dec 28 18:16:05 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Dec 28 18:16:06 volumio volumio[1398]: info: Dec 28 18:16:06 volumio volumio[1398]: ---------------------------- MPD announces state update: player Dec 28 18:16:06 volumio volumio[1398]: info: sendMpdCommand status took 33 milliseconds Dec 28 18:16:06 volumio volumio[1398]: info: ControllerMpd::getState Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::parseState Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 18:16:06 volumio volumio[1398]: info: sendMpdCommand status took 1 milliseconds Dec 28 18:16:06 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::parseState Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Dec 28 18:16:06 volumio volumio[1398]: info: ControllerMpd::pushState Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Dec 28 18:16:06 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:06 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live.mp3","artist":null,"album":null,"uri":"http://59.120.88.155:8000/live.mp3","trackType":"mp3"} Dec 28 18:16:06 volumio volumio[1398]: verbose: CURRENT POSITION 0 Dec 28 18:16:06 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Dec 28 18:16:06 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus stop Dec 28 18:16:06 volumio volumio[1398]: info: ------------------------------ 37ms Dec 28 18:16:06 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Dec 28 18:16:06 volumio volumio[1398]: info: ControllerMpd::pushState Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Dec 28 18:16:06 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:06 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live.mp3","artist":null,"album":null,"uri":"http://59.120.88.155:8000/live.mp3","trackType":"mp3"} Dec 28 18:16:06 volumio volumio[1398]: verbose: CURRENT POSITION 0 Dec 28 18:16:06 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Dec 28 18:16:06 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus play Dec 28 18:16:06 volumio volumio[1398]: info: Received an update from plugin. extracting info from payload Dec 28 18:16:06 volumio volumio[1398]: info: CoreStateMachine::pushState Dec 28 18:16:06 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Dec 28 18:16:06 volumio volumio[1398]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:16:06 volumio volumio[1398]: info: MRS: Pushing multiroomSync output Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:16:06 volumio volumio[1398]: info: CoreStateMachine::pushState Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Dec 28 18:16:06 volumio volumio[1398]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:16:06 volumio volumio[1398]: info: MRS: Pushing multiroomSync output Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:16:06 volumio volumio[1398]: info: ------------------------------ 40ms Dec 28 18:16:06 volumio volumio[1398]: info: Dec 28 18:16:06 volumio volumio[1398]: ---------------------------- MPD announces system playlist update Dec 28 18:16:06 volumio volumio[1398]: info: Ignoring MPD Status Update Dec 28 18:16:06 volumio volumio[1398]: info: Dec 28 18:16:06 volumio volumio[1398]: ---------------------------- MPD announces state update: player Dec 28 18:16:06 volumio volumio[1398]: info: ControllerMpd::getState Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand status Dec 28 18:16:06 volumio volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Dec 28 18:16:06 volumio volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Dec 28 18:16:06 volumio volumio[1398]: info: ------------------------------ 34ms Dec 28 18:16:06 volumio volumio[1398]: info: sendMpdCommand status took 33 milliseconds Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::parseState Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 18:16:06 volumio volumio[1398]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 18:16:06 volumio volumio[1398]: verbose: ControllerMpd::parseTrackInfo Dec 28 18:16:06 volumio volumio[1398]: info: ControllerMpd::pushState Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::servicePushState Dec 28 18:16:06 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:06 volumio volumio[1398]: verbose: STATE SERVICE {"status":"play","position":0,"seek":737,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"live.mp3","artist":"ClassicalFM977","album":null,"uri":"http://59.120.88.155:8000/live.mp3","trackType":"mp3"} Dec 28 18:16:06 volumio volumio[1398]: verbose: CURRENT POSITION 0 Dec 28 18:16:06 volumio volumio[1398]: info: CoreStateMachine::syncState stateService play Dec 28 18:16:06 volumio volumio[1398]: info: CoreStateMachine::syncState currentStatus play Dec 28 18:16:06 volumio volumio[1398]: info: Received an update from plugin. extracting info from payload Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 28 18:16:06 volumio volumio[1398]: info: CoreStateMachine::pushState Dec 28 18:16:06 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Dec 28 18:16:06 volumio volumio[1398]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:16:06 volumio volumio[1398]: info: MRS: Pushing multiroomSync output Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:16:06 volumio volumio[1398]: info: CoreStateMachine::pushState Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Dec 28 18:16:06 volumio volumio[1398]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:16:06 volumio volumio[1398]: info: MRS: Pushing multiroomSync output Dec 28 18:16:06 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:16:06 volumio volumio[1398]: info: ------------------------------ 70ms Dec 28 18:16:06 volumio volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Dec 28 18:16:06 volumio volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Dec 28 18:16:06 volumio volumio[1398]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 28 18:16:23 volumio go-librespot[1917]: time="2025-12-28T18:16:23+08:00" level=trace msg="sent dealer ping" Dec 28 18:16:23 volumio go-librespot[1917]: time="2025-12-28T18:16:23+08:00" level=trace msg="received dealer pong" Dec 28 18:16:30 volumio volumio[1398]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 10 Dec 28 18:16:30 volumio volumio[1398]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 10 Dec 28 18:16:30 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:16:51 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:16:53 volumio go-librespot[1917]: time="2025-12-28T18:16:53+08:00" level=trace msg="sent dealer ping" Dec 28 18:16:53 volumio go-librespot[1917]: time="2025-12-28T18:16:53+08:00" level=trace msg="received dealer pong" Dec 28 18:16:53 volumio volumio[1398]: info: Executing endpoint metavolumio Dec 28 18:16:53 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 18:16:53 volumio volumio[1398]: info: Executing endpoint metavolumio Dec 28 18:16:53 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 18:17:06 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:17:08 volumio volumio[1398]: info: Executing endpoint metavolumio Dec 28 18:17:08 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 18:17:08 volumio volumio[1398]: info: Executing endpoint metavolumio Dec 28 18:17:08 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 28 18:17:09 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 28 18:17:09 volumio volumio[1398]: info: CURURI: music-library Dec 28 18:17:09 volumio volumio[1398]: info: Preload queue cleared Dec 28 18:17:12 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 28 18:17:12 volumio volumio[1398]: info: CURURI: music-library/INTERNAL Dec 28 18:17:12 volumio volumio[1398]: info: Preload queue cleared Dec 28 18:17:13 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 28 18:17:13 volumio volumio[1398]: info: CURURI: music-library/INTERNAL/music Dec 28 18:17:13 volumio volumio[1398]: info: Preload queue cleared Dec 28 18:17:17 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 28 18:17:17 volumio volumio[1398]: info: CURURI: music-library/INTERNAL/music/日本 童謠 唱歌 西東社 - Various Artists Dec 28 18:17:17 volumio volumio[1398]: info: Preload queue cleared Dec 28 18:17:20 volumio volumio[1398]: info: Preload queue cleared Dec 28 18:17:20 volumio volumio[1398]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 28 18:17:20 volumio volumio[1398]: info: CoreStateMachine::ClearQueue Dec 28 18:17:20 volumio volumio[1398]: info: CoreStateMachine::stop Dec 28 18:17:20 volumio volumio[1398]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 18:17:20 volumio volumio[1398]: info: CoreStateMachine::stPlaybackTimer Dec 28 18:17:20 volumio volumio[1398]: info: CoreStateMachine::updateTrackBlock Dec 28 18:17:20 volumio volumio[1398]: info: CorePlayQueue::getTrackBlock Dec 28 18:17:20 volumio volumio[1398]: info: CoreStateMachine::pushState Dec 28 18:17:20 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:17:20 volumio volumio[1398]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 18:17:20 volumio volumio[1398]: info: CoreCommandRouter::volumioPushState Dec 28 18:17:20 volumio volumio[1398]: info: MRS: Pushing multiroomSync output update for this device Dec 28 18:17:20 volumio volumio[1398]: info: MRS: Pushing multiroomSync output Dec 28 18:17:20 volumio volumio[1398]: info: CoreCommandRouter::volumioGetState Dec 28 18:17:20 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:17:20 volumio volumio[1398]: info: CoreStateMachine::serviceStop Dec 28 18:17:20 volumio volumio[1398]: info: CorePlayQueue::getTrack 0 Dec 28 18:17:20 volumio volumio[1398]: info: CoreCommandRouter::serviceStop Dec 28 18:17:20 volumio volumio[1398]: info: [1766917040218] ControllerWebradio::stop Dec 28 18:17:20 volumio volumio[1398]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 18:17:20 volumio volumio[1398]: info: CorePlayQueue::clearPlayQueue Dec 28 18:17:20 volumio volumio[1398]: info: CorePlayQueue::saveQueue Dec 28 18:17:20 volumio volumio[1398]: info: CoreCommandRouter::volumioPushQueue Dec 28 18:17:20 volumio volumio[1398]: info: CoreStateMachine::addQueueItems Dec 28 18:17:20 volumio volumio[1398]: info: CorePlayQueue::addQueueItems Dec 28 18:17:20 volumio volumio[1398]: info: Preload queue cleared Dec 28 18:17:20 volumio volumio[1398]: info: Adding Item to queue: music-library/INTERNAL/music/日本 童謠 唱歌 西東社 - Various Artists Dec 28 18:17:20 volumio volumio[1398]: info: Exploding uri music-library/INTERNAL/music/日本 童謠 唱歌 西東社 - Various Artists in service mpd Dec 28 18:17:20 volumio volumio[1398]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Dec 28 18:17:20 volumio volumio[1398]: info: Dec 28 18:17:20 volumio volumio[1398]: ---------------------------- MPD announces state update: player Dec 28 18:17:20 volumio volumio[1398]: info: ALBUMART /albumart?cacheid=875&web=Various%20Artists/%E6%97%A5%E6%9C%AC%20%E7%AB%A5%E8%AC%A0%20%E5%94%B1%E6%AD%8C%20%E8%A5%BF%E6%9D%B1%E7%A4%BE/mega&path=%2Fmnt%2FINTERNAL%2Fmusic%2F%E6%97%A5%E6%9C%AC%20%E7%AB%A5%E8%AC%A0%20%E5%94%B1%E6%AD%8C%20%E8%A5%BF%E6%9D%B1%E7%A4%BE%20-%20Various%20Artists%2F%E6%97%A5%E6%9C%AC%20%E7%AB%A5%E8%AC%A0%20%E5%94%B1%E6%AD%8C%20%E8%A5%BF%E6%9D%B1%E7%A4%BE%20-%20Various%20Artists.cue&metadata=false Dec 28 18:17:20 volumio volumio[1398]: info: URI /mnt/INTERNAL/music/日本 童謠 唱歌 西東社 - Various Artists/日本 童謠 唱歌 西東社 - Various Artists.cue Dec 28 18:17:20 volumio volumio[1398]: info: ALBUMART /albumart?cacheid=875&web=Various%20Artists/%E6%97%A5%E6%9C%AC%20%E7%AB%A5%E8%AC%A0%20%E5%94%B1%E6%AD%8C%20%E8%A5%BF%E6%9D%B1%E7%A4%BE/mega&path=%2Fmnt%2FINTERNAL%2Fmusic%2F%E6%97%A5%E6%9C%AC%20%E7%AB%A5%E8%AC%A0%20%E5%94%B1%E6%AD%8C%20%E8%A5%BF%E6%9D%B1%E7%A4%BE%20-%20Various%20Artists%2F%E6%97%A5%E6%9C%AC%20%E7%AB%A5%E8%AC%A0%20%E5%94%B1%E6%AD%8C%20%E8%A5%BF%E6%9D%B1%E7%A4%BE%20-%20Various%20Artists.cue&metadata=false Dec 28 18:17:20 volumio volumio[1398]: info: URI /mnt/INTERNAL/music/日本 童謠 唱歌 西東社 - Various Artists/日本 童謠 唱歌 西東社 - Various Artists.cue Dec 28 18:17:20 volumio volumio[1398]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 18:17:20 volumio volumio[1398]: Error: Unable to resolve or reject the same promise twice Dec 28 18:17:20 volumio volumio[1398]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 28 18:17:20 volumio volumio[1398]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Dec 28 18:17:20 volumio volumio[1398]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 28 18:17:20 volumio volumio[1398]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 28 18:17:20 volumio volumio[1398]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 28 18:17:20 volumio volumio[1398]: at Socket.emit (node:events:514:28) Dec 28 18:17:20 volumio volumio[1398]: at addChunk (node:internal/streams/readable:343:12) Dec 28 18:17:20 volumio volumio[1398]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 28 18:17:20 volumio volumio[1398]: at Readable.push (node:internal/streams/readable:253:10) Dec 28 18:17:20 volumio volumio[1398]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 28 18:17:20 volumio volumio[1398]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 18:17:20 volumio sudo[3037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 18:16' Dec 28 18:17:20 volumio sudo[3037]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"