Dec 29 23:05:03 volumio01 go-librespot[1921]: time="2025-12-29T23:05:03-08:00" level=trace msg="sent dealer ping" Dec 29 23:05:04 volumio01 go-librespot[1921]: time="2025-12-29T23:05:04-08:00" level=trace msg="received dealer pong" Dec 29 23:05:19 volumio01 volumio[1164]: info: Preload queue cleared Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::ClearQueue Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::stop Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::stPlaybackTimer Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::updateTrackBlock Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrackBlock Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 6 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::serviceStop Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 6 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::serviceStop Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::stop Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::clearPlayQueue Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::saveQueue Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushQueue Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::addQueueItems Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::addQueueItems Dec 29 23:05:19 volumio01 volumio[1164]: info: Preload queue cleared Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/01 - ITZY - GOLD.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/01 - ITZY - GOLD.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/02 - ITZY - Imaginary Friend.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/02 - ITZY - Imaginary Friend.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/03 - ITZY - Bad Girls R Us.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/03 - ITZY - Bad Girls R Us.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/04 - ITZY - Supernatural.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/04 - ITZY - Supernatural.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/05 - ITZY - FIVE.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/05 - ITZY - FIVE.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/06 - ITZY Feat. Changbin of Stray Kids - VAY.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/06 - ITZY Feat. Changbin of Stray Kids - VAY.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/07 - ITZY - BORN TO BE -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/07 - ITZY - BORN TO BE -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/08 - ITZY - UNTOUCHABLE -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/08 - ITZY - UNTOUCHABLE -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/09 - ITZY - Mr. Vampire -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/09 - ITZY - Mr. Vampire -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushQueue Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::saveQueue Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::updateTrackBlock Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrackBlock Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPlay Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::play index 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::addQueueItems Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::addQueueItems Dec 29 23:05:19 volumio01 volumio[1164]: info: Preload queue cleared Dec 29 23:05:19 volumio01 volumio[1164]: info: Adding Item to queue: music-library/NAS/Synology_FLAC/ITZY - GOLD/11 - ITZY - Escalator -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: Using cached record of: music-library/NAS/Synology_FLAC/ITZY - GOLD/11 - ITZY - Escalator -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::stop Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushQueue Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::saveQueue Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::play index undefined Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::updateTrackBlock Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrackBlock Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::startPlaybackTimer Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand stop took 6 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand stop took 2 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:19 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand status took 0 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand clear took 0 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand status took 0 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand add "NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac" Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 23:05:19 volumio01 volumio[1164]: verbose: CURRENT POSITION 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState stateService stop Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState currentStatus stop Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: No code Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 2ms Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:19 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:19 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:19 volumio01 volumio[1164]: error: updateQueue error: null Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:19 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand status took 3 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 3ms Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand add "NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac" took 3 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 0ms Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 0ms Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand play Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 23:05:19 volumio01 volumio[1164]: verbose: CURRENT POSITION 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState stateService stop Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState currentStatus stop Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: No code Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 5ms Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 6ms Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:19 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:19 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 4ms Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand play took 3 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 2ms Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 2ms Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand status took 0 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand status took 0 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:19 volumio01 volumio[1164]: info: Dec 29 23:05:19 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dynamite (Final ver.)","artist":"ITZY","album":"GOLD","uri":"NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac","trackType":"flac"} Dec 29 23:05:19 volumio01 volumio[1164]: verbose: CURRENT POSITION 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState stateService play Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState currentStatus stop Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand status took 0 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dynamite (Final ver.)","artist":"ITZY","album":"GOLD","uri":"NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac","trackType":"flac"} Dec 29 23:05:19 volumio01 volumio[1164]: verbose: CURRENT POSITION 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState stateService play Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState currentStatus play Dec 29 23:05:19 volumio01 volumio[1164]: info: Received an update from plugin. extracting info from payload Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dynamite (Final ver.)","artist":"ITZY","album":"GOLD","uri":"NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac","trackType":"flac"} Dec 29 23:05:19 volumio01 volumio[1164]: verbose: CURRENT POSITION 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState stateService play Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState currentStatus play Dec 29 23:05:19 volumio01 volumio[1164]: info: Received an update from plugin. extracting info from payload Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dynamite (Final ver.)","artist":"ITZY","album":"GOLD","uri":"NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac","trackType":"flac"} Dec 29 23:05:19 volumio01 volumio[1164]: verbose: CURRENT POSITION 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState stateService play Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState currentStatus play Dec 29 23:05:19 volumio01 volumio[1164]: info: Received an update from plugin. extracting info from payload Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dynamite (Final ver.)","artist":"ITZY","album":"GOLD","uri":"NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac","trackType":"flac"} Dec 29 23:05:19 volumio01 volumio[1164]: verbose: CURRENT POSITION 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState stateService play Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState currentStatus play Dec 29 23:05:19 volumio01 volumio[1164]: info: Received an update from plugin. extracting info from payload Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 6ms Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 7ms Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 7ms Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 7ms Dec 29 23:05:19 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 29 23:05:19 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:19 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":170,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dynamite (Final ver.)","artist":"ITZY","album":"GOLD","uri":"NAS/Synology_FLAC/ITZY - GOLD/10 - ITZY - Dynamite -Final Ver.-.flac","trackType":"flac"} Dec 29 23:05:19 volumio01 volumio[1164]: verbose: CURRENT POSITION 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState stateService play Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::syncState currentStatus play Dec 29 23:05:19 volumio01 volumio[1164]: info: Received an update from plugin. extracting info from payload Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:19 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:19 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:19 volumio01 volumio[1164]: info: ------------------------------ 8ms Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:19 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:29 volumio01 volumio[1164]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Dec 29 23:05:29 volumio01 volumio[1164]: info: [1767078329534] [80s80s] handleBrowseUri curUri: 80s80s Dec 29 23:05:29 volumio01 volumio[1164]: info: Preload queue cleared Dec 29 23:05:31 volumio01 volumio[1164]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Dec 29 23:05:31 volumio01 volumio[1164]: info: [1767078331523] [80s80s] handleBrowseUri curUri: 80s80s/eighties Dec 29 23:05:31 volumio01 volumio[1164]: info: [1767078331523] [80s80s] getRadioContent url: eighties Dec 29 23:05:31 volumio01 volumio[1164]: info: Preload queue cleared Dec 29 23:05:33 volumio01 go-librespot[1921]: time="2025-12-29T23:05:33-08:00" level=trace msg="sent dealer ping" Dec 29 23:05:34 volumio01 go-librespot[1921]: time="2025-12-29T23:05:34-08:00" level=trace msg="received dealer pong" Dec 29 23:05:36 volumio01 volumio[1164]: info: Preload queue cleared Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::ClearQueue Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::stop Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::stPlaybackTimer Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::updateTrackBlock Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrackBlock Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::serviceStop Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 9 Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::serviceStop Dec 29 23:05:36 volumio01 volumio[1164]: info: ControllerMpd::stop Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::clearPlayQueue Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::saveQueue Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushQueue Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::addQueueItems Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::addQueueItems Dec 29 23:05:36 volumio01 volumio[1164]: info: Preload queue cleared Dec 29 23:05:36 volumio01 volumio[1164]: info: Adding Item to queue: webeighties/4 Dec 29 23:05:36 volumio01 volumio[1164]: info: Exploding uri webeighties/4 in service 80s80s Dec 29 23:05:36 volumio01 volumio[1164]: info: [1767078336981] [80s80s] explodeUri: webeighties/4 Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushQueue Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::saveQueue Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::updateTrackBlock Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrackBlock Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPlay Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::play index 0 Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::stop Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::play index undefined Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::startPlaybackTimer Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:36 volumio01 volumio[1164]: info: [1767078336984] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/wave/mp3-192/volumio Dec 29 23:05:36 volumio01 volumio[1164]: info: [1767078336984] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=284&count=2 Dec 29 23:05:36 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:36 volumio01 volumio[1164]: info: Dec 29 23:05:36 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:36 volumio01 volumio[1164]: info: sendMpdCommand stop took 5 milliseconds Dec 29 23:05:36 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:36 volumio01 volumio[1164]: info: Dec 29 23:05:36 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:36 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:36 volumio01 volumio[1164]: info: Dec 29 23:05:36 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:36 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:36 volumio01 volumio[1164]: info: sendMpdCommand status took 0 milliseconds Dec 29 23:05:36 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:36 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:36 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:36 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:36 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:36 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:36 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:36 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:36 volumio01 volumio[1164]: info: ------------------------------ 2ms Dec 29 23:05:36 volumio01 volumio[1164]: info: ------------------------------ 2ms Dec 29 23:05:36 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 23:05:36 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:36 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:36 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:36 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:36 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:36 volumio01 volumio[1164]: info: ------------------------------ 3ms Dec 29 23:05:36 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:36 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:36 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:37 volumio01 volumio[1164]: info: [1767078337500] [80s80s] received new event containing 2 songs. Dec 29 23:05:37 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 23:05:37 volumio01 volumio[1164]: info: sendMpdCommand stop took 0 milliseconds Dec 29 23:05:37 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 23:05:37 volumio01 volumio[1164]: info: Dec 29 23:05:37 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:37 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:37 volumio01 volumio[1164]: info: sendMpdCommand clear took 1 milliseconds Dec 29 23:05:37 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 29 23:05:37 volumio01 volumio[1164]: info: Dec 29 23:05:37 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:37 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:37 volumio01 volumio[1164]: info: Dec 29 23:05:37 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:37 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:37 volumio01 volumio[1164]: error: updateQueue error: null Dec 29 23:05:37 volumio01 volumio[1164]: error: updateQueue error: null Dec 29 23:05:37 volumio01 volumio[1164]: info: ------------------------------ 0ms Dec 29 23:05:37 volumio01 volumio[1164]: info: sendMpdCommand consume 1 took 0 milliseconds Dec 29 23:05:37 volumio01 volumio[1164]: info: ------------------------------ 0ms Dec 29 23:05:37 volumio01 volumio[1164]: info: [1767078337501] [80s80s] adding url: http://streams.80s80s.de/wave/mp3-192/volumio Dec 29 23:05:37 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" Dec 29 23:05:37 volumio01 volumio[1164]: error: updateQueue error: null Dec 29 23:05:37 volumio01 volumio[1164]: info: ------------------------------ 0ms Dec 29 23:05:37 volumio01 volumio[1164]: info: Dec 29 23:05:37 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:37 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:37 volumio01 volumio[1164]: info: sendMpdCommand add "http://streams.80s80s.de/wave/mp3-192/volumio" took 1 milliseconds Dec 29 23:05:37 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand play Dec 29 23:05:37 volumio01 volumio[1164]: info: Dec 29 23:05:37 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:37 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:37 volumio01 volumio[1164]: info: Dec 29 23:05:37 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:37 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:37 volumio01 volumio[1164]: info: ------------------------------ 0ms Dec 29 23:05:37 volumio01 volumio[1164]: info: sendMpdCommand play took 0 milliseconds Dec 29 23:05:37 volumio01 volumio[1164]: info: ------------------------------ 0ms Dec 29 23:05:37 volumio01 volumio[1164]: info: [1767078337503] [80s80s] Pushing the next song state: Depeche Mode - Just Can't Get Enough Dec 29 23:05:37 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:37 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:37 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:37 volumio01 volumio[1164]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music114/v4/39/68/f6/3968f6da-b5da-615e-9e1d-4f625dcde426/source/600x600bb.jpg","name":"Depeche Mode - Just Can't Get Enough","title":"Just Can't Get Enough","artist":"80s80s Wave","album":"","streaming":true,"disableUiControls":true,"duration":"204","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 29 23:05:37 volumio01 volumio[1164]: verbose: CURRENT POSITION 0 Dec 29 23:05:37 volumio01 volumio[1164]: info: CoreStateMachine::syncState stateService play Dec 29 23:05:37 volumio01 volumio[1164]: info: CoreStateMachine::syncState currentStatus stop Dec 29 23:05:37 volumio01 volumio[1164]: info: [1767078337503] [80s80s] PlayNextTrack API delay: 30 Dec 29 23:05:37 volumio01 volumio[1164]: info: [1767078337507] [80s80s] Setting timer to: 8497 milliseconds. Dec 29 23:05:37 volumio01 volumio[1164]: info: ------------------------------ 6ms Dec 29 23:05:37 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:37 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 1 Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand status took 24 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand status took 24 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand status took 24 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand status took 0 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:40 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:40 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:40 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 26ms Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 26ms Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 26ms Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:40 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:40 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 2ms Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:40 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:40 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:40 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:40 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:40 volumio01 volumio[1164]: info: Dec 29 23:05:40 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 0ms Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand status took 0 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:40 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:40 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:40 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:40 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:40 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:40 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:40 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:40 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:46 volumio01 volumio[1164]: info: [1767078346004] [80s80s] Pushing the next song state Depeche Mode - Just Can't Get Enough and getting next track. Dec 29 23:05:46 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:46 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:46 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:46 volumio01 volumio[1164]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music114/v4/39/68/f6/3968f6da-b5da-615e-9e1d-4f625dcde426/source/600x600bb.jpg","name":"Depeche Mode - Just Can't Get Enough","title":"Just Can't Get Enough","artist":"80s80s Wave","album":"","streaming":true,"disableUiControls":true,"duration":"204","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 29 23:05:46 volumio01 volumio[1164]: verbose: CURRENT POSITION 0 Dec 29 23:05:46 volumio01 volumio[1164]: info: CoreStateMachine::syncState stateService play Dec 29 23:05:46 volumio01 volumio[1164]: info: CoreStateMachine::syncState currentStatus play Dec 29 23:05:46 volumio01 volumio[1164]: info: Received an update from plugin. extracting info from payload Dec 29 23:05:46 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:46 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:46 volumio01 volumio[1164]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 23:05:46 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:46 volumio01 volumio[1164]: info: CoreStateMachine::pushState Dec 29 23:05:46 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:46 volumio01 volumio[1164]: info: CoreCommandRouter::volumioPushState Dec 29 23:05:46 volumio01 volumio[1164]: info: [1767078346006] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=284&count=1 Dec 29 23:05:46 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:46 volumio01 volumio[1164]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 29 23:05:46 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:46 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 1 Dec 29 23:05:58 volumio01 volumio[1164]: info: Dec 29 23:05:58 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:58 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:58 volumio01 volumio[1164]: info: Dec 29 23:05:58 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:58 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:58 volumio01 volumio[1164]: info: Dec 29 23:05:58 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:58 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:58 volumio01 volumio[1164]: info: Dec 29 23:05:58 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:58 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:58 volumio01 volumio[1164]: info: Dec 29 23:05:58 volumio01 volumio[1164]: ---------------------------- MPD announces system playlist update Dec 29 23:05:58 volumio01 volumio[1164]: info: Ignoring MPD Status Update Dec 29 23:05:58 volumio01 volumio[1164]: info: Dec 29 23:05:58 volumio01 volumio[1164]: ---------------------------- MPD announces state update: player Dec 29 23:05:58 volumio01 volumio[1164]: info: ControllerMpd::getState Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand status Dec 29 23:05:58 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:58 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:58 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:58 volumio01 volumio[1164]: info: sendMpdCommand status took 1 milliseconds Dec 29 23:05:58 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:58 volumio01 volumio[1164]: info: sendMpdCommand status took 0 milliseconds Dec 29 23:05:58 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::parseState Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:58 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:58 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:58 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:58 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:58 volumio01 volumio[1164]: info: ------------------------------ 1ms Dec 29 23:05:58 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:58 volumio01 volumio[1164]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:58 volumio01 volumio[1164]: verbose: ControllerMpd::parseTrackInfo Dec 29 23:05:58 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:58 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:58 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:58 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:58 volumio01 volumio[1164]: info: ControllerMpd::pushState Dec 29 23:05:58 volumio01 volumio[1164]: info: CoreCommandRouter::servicePushState Dec 29 23:05:58 volumio01 volumio[1164]: info: CorePlayQueue::getTrack 0 Dec 29 23:05:58 volumio01 volumio[1164]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Dec 29 23:05:58 volumio01 volumio[1164]: info: ------------------------------ 2ms Dec 29 23:05:58 volumio01 volumio[1164]: info: ------------------------------ 2ms Dec 29 23:06:01 volumio01 volumio[1164]: info: [1767078361540] [80s80s] Failed to query api, status code: 504 Dec 29 23:06:01 volumio01 volumio[1164]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 23:06:01 volumio01 volumio[1164]: Error: ENOENT: no such file or directory, open '/data/plugins/music_service/80s80s/fake-data.json' Dec 29 23:06:01 volumio01 volumio[1164]: at Object.openSync (node:fs:602:3) Dec 29 23:06:01 volumio01 volumio[1164]: at Object.readFileSync (node:fs:470:35) Dec 29 23:06:01 volumio01 volumio[1164]: at ClientRequest. (/data/plugins/music_service/80s80s/index.js:485:23) Dec 29 23:06:01 volumio01 volumio[1164]: at Object.onceWrapper (node:events:629:26) Dec 29 23:06:01 volumio01 volumio[1164]: at ClientRequest.emit (node:events:514:28) Dec 29 23:06:01 volumio01 volumio[1164]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Dec 29 23:06:01 volumio01 volumio[1164]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Dec 29 23:06:01 volumio01 volumio[1164]: at Socket.socketOnData (node:_http_client:535:22) Dec 29 23:06:01 volumio01 volumio[1164]: at Socket.emit (node:events:514:28) Dec 29 23:06:01 volumio01 volumio[1164]: at addChunk (node:internal/streams/readable:343:12) Dec 29 23:06:01 volumio01 volumio[1164]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 29 23:06:01 volumio01 volumio[1164]: at Readable.push (node:internal/streams/readable:253:10) Dec 29 23:06:01 volumio01 volumio[1164]: at TCP.onStreamRead (node:internal/stream_base_commons:190:23) { Dec 29 23:06:01 volumio01 volumio[1164]: errno: -2, Dec 29 23:06:01 volumio01 volumio[1164]: syscall: 'open', Dec 29 23:06:01 volumio01 volumio[1164]: code: 'ENOENT', Dec 29 23:06:01 volumio01 volumio[1164]: path: '/data/plugins/music_service/80s80s/fake-data.json' Dec 29 23:06:01 volumio01 volumio[1164]: } Dec 29 23:06:01 volumio01 volumio[1164]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 23:06:01 volumio01 sudo[853821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-29 23:05' Dec 29 23:06:01 volumio01 sudo[853821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"