Jun 03 00:17:00 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState Jun 03 00:17:00 volumio volumio[1316]: info: CorePlayQueue::getTrack 0 Jun 03 00:17:04 volumio volumio[1316]: info: CoreCommandRouter::volumioPause Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::pause Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::servicePause Jun 03 00:17:04 volumio volumio[1316]: info: CorePlayQueue::getTrack 0 Jun 03 00:17:04 volumio volumio[1316]: info: CoreCommandRouter::servicePause Jun 03 00:17:04 volumio volumio[1316]: info: ControllerMpd::pause Jun 03 00:17:04 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand pause Jun 03 00:17:04 volumio volumio[1316]: info: Jun 03 00:17:04 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:04 volumio volumio[1316]: info: sendMpdCommand pause took 3 milliseconds Jun 03 00:17:04 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:04 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:04 volumio volumio[1316]: info: Jun 03 00:17:04 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:04 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:04 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:04 volumio volumio[1316]: info: sendMpdCommand status took 3 milliseconds Jun 03 00:17:04 volumio volumio[1316]: info: sendMpdCommand status took 2 milliseconds Jun 03 00:17:04 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:04 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:04 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:04 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:04 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 00:17:04 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 00:17:04 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:04 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:04 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:04 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:04 volumio volumio[1316]: info: CorePlayQueue::getTrack 0 Jun 03 00:17:04 volumio volumio[1316]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":71478,"duration":402,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"357 Kbps","isStreaming":false,"title":"Over the Rainbow","artist":"Jane Monheit","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/01 Jane Monheit - Over the Rainbow.m4a","trackType":"m4a"} Jun 03 00:17:04 volumio volumio[1316]: verbose: CURRENT POSITION 0 Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::syncState stateService pause Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus pause Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:04 volumio volumio[1316]: info: CorePlayQueue::getTrack 0 Jun 03 00:17:04 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:04 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:17:04 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:04 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:04 volumio volumio[1316]: info: CorePlayQueue::getTrack 0 Jun 03 00:17:04 volumio volumio[1316]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":71478,"duration":402,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"357 Kbps","isStreaming":false,"title":"Over the Rainbow","artist":"Jane Monheit","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/01 Jane Monheit - Over the Rainbow.m4a","trackType":"m4a"} Jun 03 00:17:04 volumio volumio[1316]: verbose: CURRENT POSITION 0 Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::syncState stateService pause Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus pause Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:04 volumio volumio[1316]: info: CorePlayQueue::getTrack 0 Jun 03 00:17:04 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:04 volumio volumio[1316]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:17:04 volumio volumio[1316]: info: ------------------------------ 47ms Jun 03 00:17:04 volumio volumio[1316]: info: ------------------------------ 46ms Jun 03 00:17:05 volumio volumio[1316]: info: CoreCommandRouter::volumioPrevious Jun 03 00:17:05 volumio volumio[1316]: info: CoreStateMachine::previous Jun 03 00:17:05 volumio volumio[1316]: info: CoreStateMachine::updateTrackBlock Jun 03 00:17:05 volumio volumio[1316]: info: CorePlayQueue::getTrackBlock Jun 03 00:17:05 volumio volumio[1316]: info: CoreStateMachine::serviceClearAddPlay Jun 03 00:17:05 volumio volumio[1316]: info: CoreCommandRouter::serviceClearAddPlayTracks Jun 03 00:17:05 volumio volumio[1316]: error: WARNING: No clearAddPlayTracks method for service mpd Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioNext Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::next Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::stop Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::updateTrackBlock Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrackBlock Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 0 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::serviceStop Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 0 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::serviceStop Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::stop Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:17:06 volumio volumio[1316]: info: Jun 03 00:17:06 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand stop took 11 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::play index undefined Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::startPlaybackTimer Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::updateTrackBlock Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrackBlock Jun 03 00:17:06 volumio volumio[1316]: info: Jun 03 00:17:06 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand status took 7 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand stop took 4 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand status took 2 milliseconds Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:06 volumio volumio[1316]: info: Jun 03 00:17:06 volumio volumio[1316]: ---------------------------- MPD announces system playlist update Jun 03 00:17:06 volumio volumio[1316]: info: Ignoring MPD Status Update Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand clear took 2 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a" Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Over the Rainbow","artist":"Jane Monheit","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/01 Jane Monheit - Over the Rainbow.m4a","trackType":"m4a"} Jun 03 00:17:06 volumio volumio[1316]: verbose: CURRENT POSITION 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::syncState stateService stop Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus stop Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:06 volumio volumio[1316]: info: No code Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:06 volumio volumio[1316]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 03 00:17:06 volumio volumio[1316]: info: ------------------------------ 51ms Jun 03 00:17:06 volumio volumio[1316]: info: ------------------------------ 45ms Jun 03 00:17:06 volumio volumio[1316]: info: Jun 03 00:17:06 volumio volumio[1316]: ---------------------------- MPD announces system playlist update Jun 03 00:17:06 volumio volumio[1316]: info: Ignoring MPD Status Update Jun 03 00:17:06 volumio volumio[1316]: error: updateQueue error: null Jun 03 00:17:06 volumio volumio[1316]: info: Jun 03 00:17:06 volumio volumio[1316]: ---------------------------- MPD announces system playlist update Jun 03 00:17:06 volumio volumio[1316]: info: Ignoring MPD Status Update Jun 03 00:17:06 volumio volumio[1316]: info: ------------------------------ 44ms Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand add "USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a" took 41 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: ------------------------------ 3ms Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand play Jun 03 00:17:06 volumio volumio[1316]: info: ------------------------------ 12ms Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand play took 11 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: Jun 03 00:17:06 volumio volumio[1316]: ---------------------------- MPD announces system playlist update Jun 03 00:17:06 volumio volumio[1316]: info: Ignoring MPD Status Update Jun 03 00:17:06 volumio volumio[1316]: info: ------------------------------ 3ms Jun 03 00:17:06 volumio volumio[1316]: info: Jun 03 00:17:06 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:06 volumio volumio[1316]: info: Jun 03 00:17:06 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:06 volumio volumio[1316]: info: Jun 03 00:17:06 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand status took 6 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand status took 5 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:06 volumio volumio[1316]: info: Jun 03 00:17:06 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand status took 7 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":306,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"What a Wonderful World","artist":"Eva Cassidy","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a","trackType":"m4a"} Jun 03 00:17:06 volumio volumio[1316]: verbose: CURRENT POSITION 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::syncState stateService play Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus stop Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":306,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"What a Wonderful World","artist":"Eva Cassidy","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a","trackType":"m4a"} Jun 03 00:17:06 volumio volumio[1316]: verbose: CURRENT POSITION 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::syncState stateService play Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:17:06 volumio volumio[1316]: info: Received an update from plugin. extracting info from payload Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:06 volumio volumio[1316]: info: ------------------------------ 36ms Jun 03 00:17:06 volumio volumio[1316]: info: ------------------------------ 53ms Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand status took 42 milliseconds Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 39 milliseconds Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":306,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"What a Wonderful World","artist":"Eva Cassidy","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a","trackType":"m4a"} Jun 03 00:17:06 volumio volumio[1316]: verbose: CURRENT POSITION 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::syncState stateService play Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:17:06 volumio volumio[1316]: info: Received an update from plugin. extracting info from payload Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:06 volumio volumio[1316]: info: ------------------------------ 79ms Jun 03 00:17:06 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 03 00:17:06 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:06 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":306,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"What a Wonderful World","artist":"Eva Cassidy","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a","trackType":"m4a"} Jun 03 00:17:06 volumio volumio[1316]: verbose: CURRENT POSITION 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::syncState stateService play Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:17:06 volumio volumio[1316]: info: Received an update from plugin. extracting info from payload Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:06 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:06 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:06 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:06 volumio volumio[1316]: info: ------------------------------ 115ms Jun 03 00:17:07 volumio volumio[1316]: info: CoreCommandRouter::volumioPause Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::pause Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::servicePause Jun 03 00:17:07 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:07 volumio volumio[1316]: info: CoreCommandRouter::servicePause Jun 03 00:17:07 volumio volumio[1316]: info: ControllerMpd::pause Jun 03 00:17:07 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand pause Jun 03 00:17:07 volumio volumio[1316]: info: Jun 03 00:17:07 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:07 volumio volumio[1316]: info: sendMpdCommand pause took 4 milliseconds Jun 03 00:17:07 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:07 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:07 volumio volumio[1316]: info: Jun 03 00:17:07 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:07 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:07 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:07 volumio volumio[1316]: info: sendMpdCommand status took 3 milliseconds Jun 03 00:17:07 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:07 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:07 volumio volumio[1316]: info: sendMpdCommand status took 4 milliseconds Jun 03 00:17:07 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 00:17:07 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:07 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:07 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:07 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:07 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:07 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:07 volumio volumio[1316]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1477,"duration":306,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"371 Kbps","isStreaming":false,"title":"What a Wonderful World","artist":"Eva Cassidy","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a","trackType":"m4a"} Jun 03 00:17:07 volumio volumio[1316]: verbose: CURRENT POSITION 1 Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::syncState stateService pause Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus pause Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:07 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:07 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:07 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:17:07 volumio volumio[1316]: info: ------------------------------ 33ms Jun 03 00:17:07 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 03 00:17:07 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:07 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:07 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:07 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:07 volumio volumio[1316]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1477,"duration":306,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"371 Kbps","isStreaming":false,"title":"What a Wonderful World","artist":"Eva Cassidy","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a","trackType":"m4a"} Jun 03 00:17:07 volumio volumio[1316]: verbose: CURRENT POSITION 1 Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::syncState stateService pause Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus pause Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:07 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:07 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:07 volumio volumio[1316]: info: CoreStateMachine::stPlaybackTimer Jun 03 00:17:07 volumio volumio[1316]: info: ------------------------------ 49ms Jun 03 00:17:11 volumio volumio[1316]: info: CoreCommandRouter::volumioPlay Jun 03 00:17:11 volumio volumio[1316]: info: CoreStateMachine::play index undefined Jun 03 00:17:11 volumio volumio[1316]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 00:17:11 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:11 volumio volumio[1316]: info: CoreStateMachine::startPlaybackTimer Jun 03 00:17:11 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:11 volumio volumio[1316]: info: ControllerMpd::resume Jun 03 00:17:11 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand play Jun 03 00:17:11 volumio volumio[1316]: info: sendMpdCommand play took 7 milliseconds Jun 03 00:17:11 volumio volumio[1316]: info: Jun 03 00:17:11 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:11 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:11 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:11 volumio volumio[1316]: info: Jun 03 00:17:11 volumio volumio[1316]: ---------------------------- MPD announces state update: player Jun 03 00:17:11 volumio volumio[1316]: info: ControllerMpd::getState Jun 03 00:17:11 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand status Jun 03 00:17:11 volumio volumio[1316]: info: sendMpdCommand status took 8 milliseconds Jun 03 00:17:11 volumio volumio[1316]: info: sendMpdCommand status took 8 milliseconds Jun 03 00:17:11 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:11 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:11 volumio volumio[1316]: verbose: ControllerMpd::parseState Jun 03 00:17:11 volumio volumio[1316]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 00:17:11 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 00:17:11 volumio volumio[1316]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 00:17:11 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:11 volumio volumio[1316]: verbose: ControllerMpd::parseTrackInfo Jun 03 00:17:11 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:11 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:11 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:11 volumio volumio[1316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2153,"duration":306,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"371 Kbps","isStreaming":false,"title":"What a Wonderful World","artist":"Eva Cassidy","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a","trackType":"m4a"} Jun 03 00:17:11 volumio volumio[1316]: verbose: CURRENT POSITION 1 Jun 03 00:17:11 volumio volumio[1316]: info: CoreStateMachine::syncState stateService play Jun 03 00:17:11 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus pause Jun 03 00:17:11 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:11 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:11 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:11 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:11 volumio volumio[1316]: info: ControllerMpd::pushState Jun 03 00:17:11 volumio volumio[1316]: info: CoreCommandRouter::servicePushState Jun 03 00:17:11 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:11 volumio volumio[1316]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2473,"duration":306,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"371 Kbps","isStreaming":false,"title":"What a Wonderful World","artist":"Eva Cassidy","album":"Best Audiophile Voices","uri":"USB/MUSIC/Music/Best Audiophile Voices/02 Eva Cassidy - What a Wonderful World.m4a","trackType":"m4a"} Jun 03 00:17:11 volumio volumio[1316]: verbose: CURRENT POSITION 1 Jun 03 00:17:11 volumio volumio[1316]: info: CoreStateMachine::syncState stateService play Jun 03 00:17:11 volumio volumio[1316]: info: CoreStateMachine::syncState currentStatus play Jun 03 00:17:11 volumio volumio[1316]: info: Received an update from plugin. extracting info from payload Jun 03 00:17:11 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:11 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:11 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:11 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:11 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:11 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:11 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:11 volumio volumio[1316]: info: ------------------------------ 52ms Jun 03 00:17:11 volumio volumio[1316]: info: ------------------------------ 52ms Jun 03 00:17:14 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:14 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "30" Jun 03 00:17:14 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 0 -> 30 Jun 03 00:17:14 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":30,"mute":false,"disableVolumeControl":false} Jun 03 00:17:14 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:14 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:14 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:14 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:14 volumio python[5057]: 2026-06-03 00:17:14,950 glmvold INFO Opening GLM adapter 1781:0e39 ... Jun 03 00:17:14 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:14 volumio python[5057]: 2026-06-03 00:17:14,979 glmvold INFO Connected. Discovered 0 device(s). Jun 03 00:17:14 volumio python[5057]: 2026-06-03 00:17:14,993 glmvold INFO Set volume: 30.0% -> -10.46 dB Jun 03 00:17:16 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:16 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "39" Jun 03 00:17:16 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 30 -> 39 Jun 03 00:17:16 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":39,"mute":false,"disableVolumeControl":false} Jun 03 00:17:16 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:16 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:16 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:16 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:16 volumio python[5057]: 2026-06-03 00:17:16,783 glmvold INFO Set volume: 39.0% -> -8.18 dB Jun 03 00:17:16 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:18 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:18 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "48" Jun 03 00:17:18 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 39 -> 48 Jun 03 00:17:18 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":48,"mute":false,"disableVolumeControl":false} Jun 03 00:17:18 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:18 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:18 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:18 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:18 volumio python[5057]: 2026-06-03 00:17:18,065 glmvold INFO Set volume: 48.0% -> -6.38 dB Jun 03 00:17:18 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:19 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:19 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "100" Jun 03 00:17:19 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 48 -> 100 Jun 03 00:17:19 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":100,"mute":false,"disableVolumeControl":false} Jun 03 00:17:19 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:19 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:19 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:19 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:19 volumio python[5057]: 2026-06-03 00:17:19,532 glmvold INFO Set volume: 100.0% -> 0.00 dB Jun 03 00:17:19 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:21 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:21 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "0" Jun 03 00:17:21 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 100 -> 0 Jun 03 00:17:21 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":0,"mute":true,"disableVolumeControl":false} Jun 03 00:17:21 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:21 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:21 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:21 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:21 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:21 volumio python[5057]: 2026-06-03 00:17:21,843 glmvold INFO Set volume: 0.0% -> -120.00 dB Jun 03 00:17:23 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:23 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "7" Jun 03 00:17:23 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 0 -> 7 Jun 03 00:17:23 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":7,"mute":false,"disableVolumeControl":false} Jun 03 00:17:23 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:23 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:23 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:23 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:23 volumio python[5057]: 2026-06-03 00:17:23,445 glmvold INFO Set volume: 7.0% -> -23.10 dB Jun 03 00:17:23 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:24 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:24 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "29" Jun 03 00:17:24 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 7 -> 29 Jun 03 00:17:24 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":29,"mute":false,"disableVolumeControl":false} Jun 03 00:17:24 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:24 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:24 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:24 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:24 volumio python[5057]: 2026-06-03 00:17:24,290 glmvold INFO Set volume: 29.0% -> -10.75 dB Jun 03 00:17:24 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:26 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:26 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "45" Jun 03 00:17:26 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 29 -> 45 Jun 03 00:17:26 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":45,"mute":false,"disableVolumeControl":false} Jun 03 00:17:26 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:26 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:26 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:26 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:26 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:26 volumio python[5057]: 2026-06-03 00:17:26,636 glmvold INFO Set volume: 45.0% -> -6.94 dB Jun 03 00:17:28 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:28 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "0" Jun 03 00:17:28 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 45 -> 0 Jun 03 00:17:28 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":0,"mute":true,"disableVolumeControl":false} Jun 03 00:17:28 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:28 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:28 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:28 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:28 volumio python[5057]: 2026-06-03 00:17:28,239 glmvold INFO Set volume: 0.0% -> -120.00 dB Jun 03 00:17:28 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:28 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:28 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "1" Jun 03 00:17:28 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 0 -> 1 Jun 03 00:17:28 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":1,"mute":false,"disableVolumeControl":false} Jun 03 00:17:28 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:28 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:28 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:28 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:28 volumio python[5057]: 2026-06-03 00:17:28,884 glmvold INFO Set volume: 1.0% -> -40.00 dB Jun 03 00:17:28 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:30 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:30 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "6" Jun 03 00:17:30 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 1 -> 6 Jun 03 00:17:30 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":6,"mute":false,"disableVolumeControl":false} Jun 03 00:17:30 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:30 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:30 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:30 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:30 volumio python[5057]: 2026-06-03 00:17:30,133 glmvold INFO Set volume: 6.0% -> -24.44 dB Jun 03 00:17:30 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:32 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "0" Jun 03 00:17:32 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 6 -> 0 Jun 03 00:17:32 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":0,"mute":true,"disableVolumeControl":false} Jun 03 00:17:32 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:32 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:32 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:32 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:32 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:32 volumio python[5057]: 2026-06-03 00:17:32,262 glmvold INFO Set volume: 0.0% -> -120.00 dB Jun 03 00:17:34 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:34 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:34 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 0 -> 1 Jun 03 00:17:34 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":1,"mute":false,"disableVolumeControl":false} Jun 03 00:17:34 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:34 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:34 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:34 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:34 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:34 volumio python[5057]: 2026-06-03 00:17:34,523 glmvold INFO Set volume: 1.0% -> -40.00 dB Jun 03 00:17:36 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:36 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:36 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 1 -> 2 Jun 03 00:17:36 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":2,"mute":false,"disableVolumeControl":false} Jun 03 00:17:36 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:36 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:36 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:36 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:36 volumio python[5057]: 2026-06-03 00:17:36,187 glmvold INFO Set volume: 2.0% -> -33.98 dB Jun 03 00:17:36 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:36 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:36 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:36 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 2 -> 3 Jun 03 00:17:36 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":3,"mute":false,"disableVolumeControl":false} Jun 03 00:17:36 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:36 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:36 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:36 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:37 volumio python[5057]: 2026-06-03 00:17:37,003 glmvold INFO Set volume: 3.0% -> -30.46 dB Jun 03 00:17:37 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:37 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:37 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:37 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 3 -> 4 Jun 03 00:17:37 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":4,"mute":false,"disableVolumeControl":false} Jun 03 00:17:37 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:37 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:37 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:37 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:37 volumio python[5057]: 2026-06-03 00:17:37,941 glmvold INFO Set volume: 4.0% -> -27.96 dB Jun 03 00:17:37 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 4 -> 5 Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":5,"mute":false,"disableVolumeControl":false} Jun 03 00:17:38 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:38 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:38 volumio python[5057]: 2026-06-03 00:17:38,212 glmvold INFO Set volume: 5.0% -> -26.02 dB Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 5 -> 6 Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":6,"mute":false,"disableVolumeControl":false} Jun 03 00:17:38 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:38 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:38 volumio python[5057]: 2026-06-03 00:17:38,363 glmvold INFO Set volume: 6.0% -> -24.44 dB Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 6 -> 7 Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":7,"mute":false,"disableVolumeControl":false} Jun 03 00:17:38 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:38 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:38 volumio python[5057]: 2026-06-03 00:17:38,496 glmvold INFO Set volume: 7.0% -> -23.10 dB Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 7 -> 8 Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":8,"mute":false,"disableVolumeControl":false} Jun 03 00:17:38 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:38 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:38 volumio python[5057]: 2026-06-03 00:17:38,654 glmvold INFO Set volume: 8.0% -> -21.94 dB Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 8 -> 9 Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":9,"mute":false,"disableVolumeControl":false} Jun 03 00:17:38 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:38 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:38 volumio python[5057]: 2026-06-03 00:17:38,817 glmvold INFO Set volume: 9.0% -> -20.92 dB Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 9 -> 10 Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":10,"mute":false,"disableVolumeControl":false} Jun 03 00:17:38 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:38 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:38 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:38 volumio python[5057]: 2026-06-03 00:17:38,959 glmvold INFO Set volume: 10.0% -> -20.00 dB Jun 03 00:17:38 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 10 -> 11 Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":11,"mute":false,"disableVolumeControl":false} Jun 03 00:17:39 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:39 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:39 volumio python[5057]: 2026-06-03 00:17:39,140 glmvold INFO Set volume: 11.0% -> -19.17 dB Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 11 -> 12 Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":12,"mute":false,"disableVolumeControl":false} Jun 03 00:17:39 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:39 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:39 volumio python[5057]: 2026-06-03 00:17:39,306 glmvold INFO Set volume: 12.0% -> -18.42 dB Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 12 -> 13 Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":13,"mute":false,"disableVolumeControl":false} Jun 03 00:17:39 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:39 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:39 volumio python[5057]: 2026-06-03 00:17:39,472 glmvold INFO Set volume: 13.0% -> -17.72 dB Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 13 -> 14 Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":14,"mute":false,"disableVolumeControl":false} Jun 03 00:17:39 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:39 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:39 volumio python[5057]: 2026-06-03 00:17:39,638 glmvold INFO Set volume: 14.0% -> -17.08 dB Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 14 -> 15 Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":15,"mute":false,"disableVolumeControl":false} Jun 03 00:17:39 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:39 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:39 volumio python[5057]: 2026-06-03 00:17:39,802 glmvold INFO Set volume: 15.0% -> -16.48 dB Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 15 -> 16 Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":16,"mute":false,"disableVolumeControl":false} Jun 03 00:17:39 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:39 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:39 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:39 volumio python[5057]: 2026-06-03 00:17:39,949 glmvold INFO Set volume: 16.0% -> -15.92 dB Jun 03 00:17:39 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:40 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:40 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:40 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 16 -> 17 Jun 03 00:17:40 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":17,"mute":false,"disableVolumeControl":false} Jun 03 00:17:40 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:40 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:40 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:40 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:40 volumio python[5057]: 2026-06-03 00:17:40,131 glmvold INFO Set volume: 17.0% -> -15.39 dB Jun 03 00:17:40 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:41 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:41 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:41 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 17 -> 18 Jun 03 00:17:41 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":18,"mute":false,"disableVolumeControl":false} Jun 03 00:17:41 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:41 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:41 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:41 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:41 volumio python[5057]: 2026-06-03 00:17:41,820 glmvold INFO Set volume: 18.0% -> -14.89 dB Jun 03 00:17:41 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:41 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:41 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:41 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 18 -> 19 Jun 03 00:17:41 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":19,"mute":false,"disableVolumeControl":false} Jun 03 00:17:41 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:41 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:41 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:41 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:42 volumio python[5057]: 2026-06-03 00:17:42,000 glmvold INFO Set volume: 19.0% -> -14.42 dB Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 19 -> 20 Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":20,"mute":false,"disableVolumeControl":false} Jun 03 00:17:42 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:42 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:42 volumio python[5057]: 2026-06-03 00:17:42,162 glmvold INFO Set volume: 20.0% -> -13.98 dB Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 20 -> 21 Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":21,"mute":false,"disableVolumeControl":false} Jun 03 00:17:42 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:42 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:42 volumio python[5057]: 2026-06-03 00:17:42,348 glmvold INFO Set volume: 21.0% -> -13.56 dB Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 21 -> 22 Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":22,"mute":false,"disableVolumeControl":false} Jun 03 00:17:42 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:42 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:42 volumio python[5057]: 2026-06-03 00:17:42,508 glmvold INFO Set volume: 22.0% -> -13.15 dB Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 22 -> 23 Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":23,"mute":false,"disableVolumeControl":false} Jun 03 00:17:42 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:42 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:42 volumio python[5057]: 2026-06-03 00:17:42,675 glmvold INFO Set volume: 23.0% -> -12.77 dB Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 23 -> 24 Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":24,"mute":false,"disableVolumeControl":false} Jun 03 00:17:42 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:42 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:42 volumio python[5057]: 2026-06-03 00:17:42,868 glmvold INFO Set volume: 24.0% -> -12.40 dB Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 24 -> 25 Jun 03 00:17:42 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":25,"mute":false,"disableVolumeControl":false} Jun 03 00:17:42 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:42 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:42 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:43 volumio python[5057]: 2026-06-03 00:17:43,022 glmvold INFO Set volume: 25.0% -> -12.04 dB Jun 03 00:17:43 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:43 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:43 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "+" Jun 03 00:17:43 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 25 -> 26 Jun 03 00:17:43 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":26,"mute":false,"disableVolumeControl":false} Jun 03 00:17:43 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:43 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:43 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:43 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:43 volumio python[5057]: 2026-06-03 00:17:43,783 glmvold INFO Set volume: 26.0% -> -11.70 dB Jun 03 00:17:43 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:44 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:44 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "81" Jun 03 00:17:44 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 26 -> 81 Jun 03 00:17:44 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":81,"mute":false,"disableVolumeControl":false} Jun 03 00:17:44 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:44 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:44 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:44 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:44 volumio python[5057]: 2026-06-03 00:17:44,470 glmvold INFO Set volume: 81.0% -> -1.83 dB Jun 03 00:17:44 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:44 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:44 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "93" Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 81 -> 93 Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":93,"mute":false,"disableVolumeControl":false} Jun 03 00:17:45 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:45 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:45 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:45 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:45 volumio python[5057]: 2026-06-03 00:17:45,053 glmvold INFO Set volume: 93.0% -> -0.63 dB Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:45 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "0" Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 93 -> 0 Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":0,"mute":true,"disableVolumeControl":false} Jun 03 00:17:45 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:45 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:45 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:45 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:45 volumio python[5057]: 2026-06-03 00:17:45,498 glmvold INFO Set volume: 0.0% -> -120.00 dB Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:17:45 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] alsavolume: Set volume "25" Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] updateVolumeImp: 0 -> 25 Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] getVolumeObject: {"vol":25,"mute":false,"disableVolumeControl":false} Jun 03 00:17:45 volumio volumio[1316]: info: CoreStateMachine::pushState Jun 03 00:17:45 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:17:45 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 00:17:45 volumio volumio[1316]: info: CoreCommandRouter::volumioPushState Jun 03 00:17:45 volumio python[5057]: 2026-06-03 00:17:45,888 glmvold INFO Set volume: 25.0% -> -12.04 dB Jun 03 00:17:45 volumio volumio[1316]: info: [GEVOL] updateVolumeImp returned: OK Jun 03 00:18:14 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState Jun 03 00:18:14 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:18:15 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 00:18:15 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 03 00:18:17 volumio volumio[1316]: info: Disabling plugin gevol Jun 03 00:18:17 volumio volumio[1316]: info: Done. Jun 03 00:18:19 volumio volumio[1316]: info: CoreCommandRouter::volumioGetState Jun 03 00:18:19 volumio volumio[1316]: info: CorePlayQueue::getTrack 1 Jun 03 00:18:20 volumio volumio[1316]: info: CoreCommandRouter::executeOnPlugin: gevol , alsavolume Jun 03 00:18:20 volumio volumio[1316]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 00:18:20 volumio volumio[1316]: TypeError: Cannot read properties of undefined (reading 'then') Jun 03 00:18:20 volumio volumio[1316]: at CoreCommandRouter.volumiosetvolume (/volumio/app/index.js:136:10) Jun 03 00:18:20 volumio volumio[1316]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:304:33) Jun 03 00:18:20 volumio volumio[1316]: at Socket.emit (node:events:514:28) Jun 03 00:18:20 volumio volumio[1316]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 03 00:18:20 volumio volumio[1316]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jun 03 00:18:20 volumio volumio[1316]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 00:18:21 volumio sudo[8612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-03 00:17' Jun 03 00:18:21 volumio sudo[8612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"