Dec 10 22:36:04 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 10 22:36:04 volumio systemd[1]: peppymeterbasic.service: Consumed 2.528s CPU time. Dec 10 22:36:10 volumio volumio[1529]: info: CoreCommandRouter::volumioStop Dec 10 22:36:10 volumio volumio[1529]: info: CoreStateMachine::stop Dec 10 22:36:10 volumio volumio[1529]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 22:36:10 volumio volumio[1529]: info: CoreStateMachine::stPlaybackTimer Dec 10 22:36:10 volumio volumio[1529]: info: CoreStateMachine::updateTrackBlock Dec 10 22:36:10 volumio volumio[1529]: info: CorePlayQueue::getTrackBlock Dec 10 22:36:10 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:10 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:10 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:36:10 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:10 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:10 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:10 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:10 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:10 volumio volumio[1529]: info: CoreStateMachine::serviceStop Dec 10 22:36:10 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:10 volumio volumio[1529]: info: CoreCommandRouter::serviceStop Dec 10 22:36:10 volumio volumio[1529]: info: [1765395370986] ControllerWebradio::stop Dec 10 22:36:10 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 22:36:10 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 10 22:36:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 10 22:36:11 volumio sudo[2850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 10 22:36:11 volumio sudo[2850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:11 volumio volumio[1529]: info: Dec 10 22:36:11 volumio volumio[1529]: ---------------------------- MPD announces state update: player Dec 10 22:36:11 volumio volumio[1529]: info: sendMpdCommand stop took 31 milliseconds Dec 10 22:36:11 volumio volumio[1529]: info: ControllerMpd::getState Dec 10 22:36:11 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:36:11 volumio volumio[1529]: info: sendMpdCommand status took 4 milliseconds Dec 10 22:36:11 volumio volumio[1529]: verbose: ControllerMpd::parseState Dec 10 22:36:11 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:36:11 volumio volumio[1529]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 10 22:36:11 volumio volumio[1529]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:36:11 volumio volumio[1529]: info: ControllerMpd::pushState Dec 10 22:36:11 volumio volumio[1529]: info: CoreCommandRouter::servicePushState Dec 10 22:36:11 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:11 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:11 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:36:11 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:11 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:11 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:11 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:11 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:11 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:11 volumio volumio[1529]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 10 22:36:11 volumio volumio[1529]: info: ------------------------------ 26ms Dec 10 22:36:11 volumio sudo[2850]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:11 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 10 22:36:11 volumio volumio[1529]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 10 22:36:11 volumio volumio[1529]: info: peppymeterbasic Daemon Stop Dec 10 22:36:11 volumio sudo[2857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 10 22:36:11 volumio sudo[2857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:11 volumio sudo[2857]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:11 volumio volumio[1529]: info: peppymeterbasic Daemon Stop Dec 10 22:36:12 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:12 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:12 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 10 22:36:12 volumio volumio[1529]: info: CoreCommandRouter::volumioPlay Dec 10 22:36:12 volumio volumio[1529]: info: CoreStateMachine::play index undefined Dec 10 22:36:12 volumio volumio[1529]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 22:36:12 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:12 volumio volumio[1529]: info: CoreStateMachine::startPlaybackTimer Dec 10 22:36:12 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:12 volumio volumio[1529]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 22:36:12 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 22:36:12 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 10 22:36:12 volumio volumio[1529]: info: [1765395372673] ControllerWebradio::clearAddPlayTrack Dec 10 22:36:12 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 22:36:12 volumio volumio[1529]: info: sendMpdCommand stop took 2 milliseconds Dec 10 22:36:12 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 22:36:12 volumio volumio[1529]: info: Dec 10 22:36:12 volumio volumio[1529]: ---------------------------- MPD announces system playlist update Dec 10 22:36:12 volumio volumio[1529]: info: Ignoring MPD Status Update Dec 10 22:36:12 volumio volumio[1529]: info: sendMpdCommand clear took 1 milliseconds Dec 10 22:36:12 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/rock-flacm" Dec 10 22:36:12 volumio volumio[1529]: error: updateQueue error: null Dec 10 22:36:12 volumio volumio[1529]: info: ------------------------------ 2ms Dec 10 22:36:12 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" Dec 10 22:36:12 volumio volumio[1529]: info: Dec 10 22:36:12 volumio volumio[1529]: ---------------------------- MPD announces system playlist update Dec 10 22:36:12 volumio volumio[1529]: info: Ignoring MPD Status Update Dec 10 22:36:12 volumio volumio[1529]: info: sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" took 1 milliseconds Dec 10 22:36:12 volumio volumio[1529]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 10 22:36:12 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand play Dec 10 22:36:12 volumio volumio[1529]: info: ------------------------------ 1ms Dec 10 22:36:12 volumio volumio[1529]: info: sendMpdCommand play took 1 milliseconds Dec 10 22:36:12 volumio volumio[1529]: info: Dec 10 22:36:12 volumio volumio[1529]: ---------------------------- MPD announces state update: player Dec 10 22:36:12 volumio volumio[1529]: info: ControllerMpd::getState Dec 10 22:36:12 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:36:13 volumio volumio[1529]: info: Executing endpoint metavolumio Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 10 22:36:13 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 10 22:36:13 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 10 22:36:13 volumio volumio[1529]: info: sendMpdCommand status took 18 milliseconds Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::parseState Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:36:13 volumio volumio[1529]: info: Dec 10 22:36:13 volumio volumio[1529]: ---------------------------- MPD announces state update: player Dec 10 22:36:13 volumio volumio[1529]: info: ControllerMpd::getState Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:36:13 volumio volumio[1529]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:36:13 volumio volumio[1529]: info: ControllerMpd::pushState Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::servicePushState Dec 10 22:36:13 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:13 volumio volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Dec 10 22:36:13 volumio volumio[1529]: verbose: CURRENT POSITION 0 Dec 10 22:36:13 volumio volumio[1529]: info: CoreStateMachine::syncState stateService play Dec 10 22:36:13 volumio volumio[1529]: info: CoreStateMachine::syncState currentStatus stop Dec 10 22:36:13 volumio volumio[1529]: info: ------------------------------ 20ms Dec 10 22:36:13 volumio volumio[1529]: info: sendMpdCommand status took 1 milliseconds Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::parseState Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:36:13 volumio volumio[1529]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:36:13 volumio volumio[1529]: info: ControllerMpd::pushState Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::servicePushState Dec 10 22:36:13 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:13 volumio volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Dec 10 22:36:13 volumio volumio[1529]: verbose: CURRENT POSITION 0 Dec 10 22:36:13 volumio volumio[1529]: info: CoreStateMachine::syncState stateService play Dec 10 22:36:13 volumio volumio[1529]: info: CoreStateMachine::syncState currentStatus play Dec 10 22:36:13 volumio volumio[1529]: info: Received an update from plugin. extracting info from payload Dec 10 22:36:13 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:13 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:13 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:13 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:13 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:13 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:13 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:13 volumio volumio[1529]: info: ------------------------------ 26ms Dec 10 22:36:13 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 10 22:36:13 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 10 22:36:13 volumio sudo[2865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 10 22:36:13 volumio sudo[2865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:13 volumio sudo[2867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 10 22:36:13 volumio sudo[2867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:13 volumio volumio[1529]: info: Dec 10 22:36:13 volumio volumio[1529]: ---------------------------- MPD announces system playlist update Dec 10 22:36:13 volumio volumio[1529]: info: Ignoring MPD Status Update Dec 10 22:36:13 volumio volumio[1529]: info: Dec 10 22:36:13 volumio volumio[1529]: ---------------------------- MPD announces state update: player Dec 10 22:36:13 volumio volumio[1529]: info: ControllerMpd::getState Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:36:13 volumio volumio[1529]: info: ------------------------------ 13ms Dec 10 22:36:13 volumio volumio[1529]: info: sendMpdCommand status took 13 milliseconds Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::parseState Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:36:13 volumio volumio[1529]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 10 22:36:13 volumio volumio[1529]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:36:13 volumio volumio[1529]: info: ControllerMpd::pushState Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::servicePushState Dec 10 22:36:13 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:13 volumio volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Dec 10 22:36:13 volumio volumio[1529]: verbose: CURRENT POSITION 0 Dec 10 22:36:13 volumio volumio[1529]: info: CoreStateMachine::syncState stateService play Dec 10 22:36:13 volumio volumio[1529]: info: CoreStateMachine::syncState currentStatus play Dec 10 22:36:13 volumio volumio[1529]: info: Received an update from plugin. extracting info from payload Dec 10 22:36:13 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:13 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:13 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:13 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:13 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:13 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:13 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:13 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:13 volumio volumio[1529]: info: ------------------------------ 43ms Dec 10 22:36:13 volumio volumio[1529]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 10 22:36:13 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 10 22:36:13 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 10 22:36:13 volumio sudo[2874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 10 22:36:13 volumio sudo[2874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:13 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 10 22:36:13 volumio sudo[2877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 10 22:36:13 volumio sudo[2877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:13 volumio sudo[2867]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:13 volumio sudo[2865]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:13 volumio volumio[1529]: info: peppymeterbasic Daemon Started Dec 10 22:36:13 volumio volumio[1529]: info: peppymeterbasic Daemon Started Dec 10 22:36:13 volumio sudo[2877]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:13 volumio sudo[2874]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:13 volumio volumio[1529]: info: peppymeterbasic Daemon Started Dec 10 22:36:13 volumio volumio[1529]: info: peppymeterbasic Daemon Started Dec 10 22:36:14 volumio volumio[1529]: info: Dec 10 22:36:14 volumio volumio[1529]: ---------------------------- MPD announces system playlist update Dec 10 22:36:14 volumio volumio[1529]: info: Ignoring MPD Status Update Dec 10 22:36:14 volumio volumio[1529]: info: Dec 10 22:36:14 volumio volumio[1529]: ---------------------------- MPD announces state update: player Dec 10 22:36:14 volumio volumio[1529]: info: ControllerMpd::getState Dec 10 22:36:14 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:36:14 volumio volumio[1529]: info: ------------------------------ 2ms Dec 10 22:36:14 volumio volumio[1529]: info: sendMpdCommand status took 1 milliseconds Dec 10 22:36:14 volumio volumio[1529]: verbose: ControllerMpd::parseState Dec 10 22:36:14 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:36:14 volumio volumio[1529]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 10 22:36:14 volumio volumio[1529]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:36:14 volumio volumio[1529]: info: ControllerMpd::pushState Dec 10 22:36:14 volumio volumio[1529]: info: CoreCommandRouter::servicePushState Dec 10 22:36:14 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:14 volumio volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2567,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Decemberists - Down By The Water","artist":"Radio Paradise: Rock Mix (flac) - playlist: radioparadise.com","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Dec 10 22:36:14 volumio volumio[1529]: verbose: CURRENT POSITION 0 Dec 10 22:36:14 volumio volumio[1529]: info: CoreStateMachine::syncState stateService play Dec 10 22:36:14 volumio volumio[1529]: info: CoreStateMachine::syncState currentStatus play Dec 10 22:36:14 volumio volumio[1529]: info: Received an update from plugin. extracting info from payload Dec 10 22:36:14 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:14 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:36:14 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:14 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:14 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:14 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:14 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:14 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:14 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:14 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:14 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:14 volumio volumio[1529]: info: ------------------------------ 23ms Dec 10 22:36:14 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 10 22:36:14 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 10 22:36:14 volumio sudo[2894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 10 22:36:14 volumio sudo[2894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:14 volumio sudo[2896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 10 22:36:14 volumio sudo[2896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:14 volumio sudo[2894]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:14 volumio sudo[2896]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:14 volumio volumio[1529]: info: peppymeterbasic Daemon Started Dec 10 22:36:14 volumio volumio[1529]: info: peppymeterbasic Daemon Started Dec 10 22:36:16 volumio volumio[1529]: info: Executing endpoint metavolumio Dec 10 22:36:16 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 10 22:36:16 volumio volumio[1529]: info: Executing endpoint metavolumio Dec 10 22:36:16 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 10 22:36:33 volumio volumio[1529]: info: Tunnel connection is inactive, restarting it Dec 10 22:36:33 volumio volumio[1529]: info: Starting Tunnel 1 Dec 10 22:36:33 volumio volumio[1529]: info: Starting Tunnel Connection Checker Dec 10 22:36:33 volumio sudo[2978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 10 22:36:33 volumio sudo[2978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:33 volumio autossh[2355]: received signal to exit (15) Dec 10 22:36:33 volumio systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Dec 10 22:36:33 volumio systemd[1]: sshtunnel.service: Deactivated successfully. Dec 10 22:36:33 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Dec 10 22:36:33 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 10 22:36:33 volumio sudo[2978]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:33 volumio volumio[1529]: info: Remote SSH Started Dec 10 22:36:33 volumio autossh[2981]: port set to 0, monitoring disabled Dec 10 22:36:33 volumio autossh[2981]: starting ssh (count 1) Dec 10 22:36:33 volumio autossh[2981]: ssh child pid is 2984 Dec 10 22:36:33 volumio volumio[1529]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 10 22:36:33 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:33 volumio volumiossh-tunnel[2984]: Warning: Permanently added '[eu12.myvolumio.org]:2222' (ED25519) to the list of known hosts. Dec 10 22:36:39 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 10 22:36:39 volumio volumio[1529]: info: Preload queue cleared Dec 10 22:36:41 volumio volumio[1529]: info: Preload queue cleared Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::ClearQueue Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::stop Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::stPlaybackTimer Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::updateTrackBlock Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::getTrackBlock Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:41 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:41 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::serviceStop Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::serviceStop Dec 10 22:36:41 volumio volumio[1529]: info: [1765395401846] ControllerWebradio::stop Dec 10 22:36:41 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::clearPlayQueue Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::saveQueue Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::volumioPushQueue Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::addQueueItems Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::addQueueItems Dec 10 22:36:41 volumio volumio[1529]: info: Preload queue cleared Dec 10 22:36:41 volumio volumio[1529]: info: Adding Item to queue: https://europaplus.hostingradio.ru:8014/europaplus320.mp3 Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::volumioPushQueue Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::saveQueue Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::updateTrackBlock Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::getTrackBlock Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::volumioPlay Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::play index 0 Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::stop Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::play index undefined Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:41 volumio volumio[1529]: info: CoreStateMachine::startPlaybackTimer Dec 10 22:36:41 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::volumioGetVisibleSources Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 10 22:36:41 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 10 22:36:41 volumio volumio[1529]: info: [1765395401865] ControllerWebradio::clearAddPlayTrack Dec 10 22:36:41 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 22:36:41 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 10 22:36:41 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 10 22:36:41 volumio volumio[1529]: info: Dec 10 22:36:41 volumio volumio[1529]: ---------------------------- MPD announces state update: player Dec 10 22:36:41 volumio volumio[1529]: info: sendMpdCommand stop took 37 milliseconds Dec 10 22:36:41 volumio volumio[1529]: info: ControllerMpd::getState Dec 10 22:36:41 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:36:41 volumio volumio[1529]: info: sendMpdCommand stop took 17 milliseconds Dec 10 22:36:41 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 22:36:41 volumio sudo[3019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 10 22:36:41 volumio sudo[3019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:41 volumio volumio[1529]: info: sendMpdCommand status took 1 milliseconds Dec 10 22:36:41 volumio volumio[1529]: verbose: ControllerMpd::parseState Dec 10 22:36:41 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:36:41 volumio volumio[1529]: info: Dec 10 22:36:41 volumio volumio[1529]: ---------------------------- MPD announces system playlist update Dec 10 22:36:41 volumio volumio[1529]: info: Ignoring MPD Status Update Dec 10 22:36:41 volumio volumio[1529]: info: sendMpdCommand clear took 2 milliseconds Dec 10 22:36:41 volumio volumio[1529]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 10 22:36:41 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand load "https://europaplus.hostingradio.ru:8014/europaplus320.mp3" Dec 10 22:36:41 volumio volumio[1529]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:36:41 volumio volumio[1529]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 10 22:36:41 volumio volumio[1529]: info: ------------------------------ 4ms Dec 10 22:36:41 volumio volumio[1529]: error: updateQueue error: null Dec 10 22:36:41 volumio volumio[1529]: info: ------------------------------ 2ms Dec 10 22:36:41 volumio volumio[1529]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 10 22:36:41 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 10 22:36:41 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 10 22:36:41 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 10 22:36:41 volumio systemd[1]: peppymeterbasic.service: Consumed 3.372s CPU time. Dec 10 22:36:41 volumio sudo[3019]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:41 volumio volumio[1529]: info: peppymeterbasic Daemon Stop Dec 10 22:36:43 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand add "https://europaplus.hostingradio.ru:8014/europaplus320.mp3" Dec 10 22:36:43 volumio volumio[1529]: info: Dec 10 22:36:43 volumio volumio[1529]: ---------------------------- MPD announces system playlist update Dec 10 22:36:43 volumio volumio[1529]: info: Ignoring MPD Status Update Dec 10 22:36:43 volumio volumio[1529]: info: sendMpdCommand add "https://europaplus.hostingradio.ru:8014/europaplus320.mp3" took 1 milliseconds Dec 10 22:36:43 volumio volumio[1529]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 10 22:36:43 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand play Dec 10 22:36:43 volumio volumio[1529]: info: ------------------------------ 1ms Dec 10 22:36:43 volumio volumio[1529]: info: sendMpdCommand play took 1 milliseconds Dec 10 22:36:43 volumio volumio[1529]: info: Executing endpoint metavolumio Dec 10 22:36:43 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 10 22:36:44 volumio volumio[1529]: info: Dec 10 22:36:44 volumio volumio[1529]: ---------------------------- MPD announces state update: player Dec 10 22:36:44 volumio volumio[1529]: info: ControllerMpd::getState Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:36:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 10 22:36:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Dec 10 22:36:44 volumio volumio[1529]: info: Dec 10 22:36:44 volumio volumio[1529]: ---------------------------- MPD announces state update: player Dec 10 22:36:44 volumio volumio[1529]: info: sendMpdCommand status took 18 milliseconds Dec 10 22:36:44 volumio volumio[1529]: info: ControllerMpd::getState Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::parseState Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:36:44 volumio volumio[1529]: info: sendMpdCommand status took 1 milliseconds Dec 10 22:36:44 volumio volumio[1529]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::parseState Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:36:44 volumio volumio[1529]: info: ControllerMpd::pushState Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::servicePushState Dec 10 22:36:44 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:44 volumio volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"europaplus320.mp3","artist":null,"album":null,"uri":"https://europaplus.hostingradio.ru:8014/europaplus320.mp3","trackType":"mp3"} Dec 10 22:36:44 volumio volumio[1529]: verbose: CURRENT POSITION 0 Dec 10 22:36:44 volumio volumio[1529]: info: CoreStateMachine::syncState stateService play Dec 10 22:36:44 volumio volumio[1529]: info: CoreStateMachine::syncState currentStatus stop Dec 10 22:36:44 volumio volumio[1529]: info: ------------------------------ 20ms Dec 10 22:36:44 volumio volumio[1529]: info: Dec 10 22:36:44 volumio volumio[1529]: ---------------------------- MPD announces system playlist update Dec 10 22:36:44 volumio volumio[1529]: info: Ignoring MPD Status Update Dec 10 22:36:44 volumio volumio[1529]: info: Dec 10 22:36:44 volumio volumio[1529]: ---------------------------- MPD announces state update: player Dec 10 22:36:44 volumio volumio[1529]: info: ControllerMpd::getState Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand status Dec 10 22:36:44 volumio volumio[1529]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:36:44 volumio volumio[1529]: info: ControllerMpd::pushState Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::servicePushState Dec 10 22:36:44 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:44 volumio volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"europaplus320.mp3","artist":"europaplus","album":null,"uri":"https://europaplus.hostingradio.ru:8014/europaplus320.mp3","trackType":"mp3"} Dec 10 22:36:44 volumio volumio[1529]: verbose: CURRENT POSITION 0 Dec 10 22:36:44 volumio volumio[1529]: info: CoreStateMachine::syncState stateService play Dec 10 22:36:44 volumio volumio[1529]: info: CoreStateMachine::syncState currentStatus play Dec 10 22:36:44 volumio volumio[1529]: info: Received an update from plugin. extracting info from payload Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 22:36:44 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:44 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:44 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:44 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:44 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:44 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:44 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:44 volumio volumio[1529]: info: ------------------------------ 25ms Dec 10 22:36:44 volumio volumio[1529]: info: ------------------------------ 22ms Dec 10 22:36:44 volumio volumio[1529]: info: sendMpdCommand status took 22 milliseconds Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::parseState Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 22:36:44 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 10 22:36:44 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 10 22:36:44 volumio sudo[3038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 10 22:36:44 volumio sudo[3038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:44 volumio sudo[3040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 10 22:36:44 volumio volumio[1529]: info: sendMpdCommand playlistinfo took 30 milliseconds Dec 10 22:36:44 volumio volumio[1529]: verbose: ControllerMpd::parseTrackInfo Dec 10 22:36:44 volumio volumio[1529]: info: ControllerMpd::pushState Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::servicePushState Dec 10 22:36:44 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:44 volumio volumio[1529]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"europaplus320.mp3","artist":"europaplus","album":null,"uri":"https://europaplus.hostingradio.ru:8014/europaplus320.mp3","trackType":"mp3"} Dec 10 22:36:44 volumio volumio[1529]: verbose: CURRENT POSITION 0 Dec 10 22:36:44 volumio volumio[1529]: info: CoreStateMachine::syncState stateService play Dec 10 22:36:44 volumio volumio[1529]: info: CoreStateMachine::syncState currentStatus play Dec 10 22:36:44 volumio sudo[3040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:44 volumio volumio[1529]: info: Received an update from plugin. extracting info from payload Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 22:36:44 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:44 volumio volumio[1529]: info: CorePlayQueue::getTrack 0 Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:44 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:44 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:44 volumio volumio[1529]: info: CoreStateMachine::pushState Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::volumioPushState Dec 10 22:36:44 volumio volumio[1529]: info: MRS: Pushing multiroomSync output update for this device Dec 10 22:36:44 volumio volumio[1529]: info: MRS: Pushing multiroomSync output Dec 10 22:36:44 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:44 volumio volumio[1529]: info: ------------------------------ 84ms Dec 10 22:36:44 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 10 22:36:44 volumio volumio[1529]: info: PeppyMeterBasic ---peppymeterbasic status play Dec 10 22:36:44 volumio sudo[3047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 10 22:36:44 volumio sudo[3045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Dec 10 22:36:44 volumio sudo[3047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:44 volumio sudo[3045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 22:36:44 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 10 22:36:44 volumio sudo[3038]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:44 volumio volumio[1529]: info: peppymeterbasic Daemon Started Dec 10 22:36:44 volumio sudo[3040]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:44 volumio volumio[1529]: info: peppymeterbasic Daemon Started Dec 10 22:36:44 volumio volumio[1529]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 10 22:36:44 volumio sudo[3047]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:44 volumio sudo[3045]: pam_unix(sudo:session): session closed for user root Dec 10 22:36:44 volumio volumio[1529]: info: peppymeterbasic Daemon Started Dec 10 22:36:44 volumio volumio[1529]: info: peppymeterbasic Daemon Started Dec 10 22:36:47 volumio volumio[1529]: info: Executing endpoint metavolumio Dec 10 22:36:47 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 10 22:36:47 volumio volumio[1529]: info: Executing endpoint metavolumio Dec 10 22:36:47 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 10 22:36:47 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 22:36:47 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 22:36:47 volumio volumio[1529]: info: Discovery: Getting this device information Dec 10 22:36:47 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:36:47 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 22:36:47 volumio volumio[1529]: verbose: New Socket.io Connection to 192.168.0.12:3000 from 192.168.0.13 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 10 22:36:47 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 10 22:36:47 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 10 22:37:12 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 22:37:12 volumio volumio[1529]: info: CURURI: playlists Dec 10 22:37:12 volumio volumio[1529]: info: Listing playlists Dec 10 22:37:12 volumio volumio[1529]: info: Preload queue cleared Dec 10 22:37:16 volumio volumio[1529]: info: Preload queue cleared Dec 10 22:37:16 volumio volumio[1529]: info: Preloading song: spotify:track:22ckf5hC1cnHnFPY6pxINQ Dec 10 22:37:16 volumio volumio[1529]: info: Preloading song: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22STI%20Hymn%22%2C%22artist%22%3A%22STI%20College%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FWBjx7ZVsu2k%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3nONuUs5Mfn-wxLAq7ylVUAJLZSZA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22WBjx7ZVsu2k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22WBjx7ZVsu2k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Dec 10 22:37:16 volumio volumio[1529]: info: Preloading song: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Summer%20Vibes%20Deep%20House%20Mix%202023%20%E2%9B%85%20Avicii%2C%20Maroon%205%2C%20Coldplay%2C%20Ellie%20Goulding%2C%20Selena%20Gomez%20Cover%22%2C%22artist%22%3A%22Magic%20Club%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FF1Fqs2WJiOY%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3miRG4N7rlRKDe7DzEG7z09qRXKQA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22F1Fqs2WJiOY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22F1Fqs2WJiOY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Dec 10 22:37:16 volumio volumio[1529]: info: Exploding uri spotify:track:22ckf5hC1cnHnFPY6pxINQ in service spop Dec 10 22:37:16 volumio volumio[1529]: info: Exploding uri ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22STI%20Hymn%22%2C%22artist%22%3A%22STI%20College%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FWBjx7ZVsu2k%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3nONuUs5Mfn-wxLAq7ylVUAJLZSZA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22WBjx7ZVsu2k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22WBjx7ZVsu2k%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D in service ytmusic Dec 10 22:37:16 volumio volumio[1529]: info: Exploding uri ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Summer%20Vibes%20Deep%20House%20Mix%202023%20%E2%9B%85%20Avicii%2C%20Maroon%205%2C%20Coldplay%2C%20Ellie%20Goulding%2C%20Selena%20Gomez%20Cover%22%2C%22artist%22%3A%22Magic%20Club%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FF1Fqs2WJiOY%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3miRG4N7rlRKDe7DzEG7z09qRXKQA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22F1Fqs2WJiOY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22F1Fqs2WJiOY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D in service ytmusic Dec 10 22:37:26 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 22:37:26 volumio volumio[1529]: info: CURURI: music-library Dec 10 22:37:26 volumio volumio[1529]: error: Failed LSINFO: null Dec 10 22:37:26 volumio volumio[1529]: info: Preload queue cleared Dec 10 22:37:28 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 22:37:28 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 22:37:28 volumio volumio[1529]: info: Discovery: Getting this device information Dec 10 22:37:28 volumio volumio[1529]: info: CoreCommandRouter::volumioGetState Dec 10 22:37:28 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 22:37:28 volumio volumio[1529]: verbose: New Socket.io Connection to 192.168.0.12:3000 from 192.168.0.13 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 10 22:37:28 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 10 22:37:28 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 10 22:37:29 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 22:37:29 volumio volumio[1529]: info: CURURI: albums:// Dec 10 22:37:29 volumio volumio[1529]: info: listAlbums - loading Albums from cache Dec 10 22:37:29 volumio volumio[1529]: info: Preload queue cleared Dec 10 22:37:32 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 22:37:32 volumio volumio[1529]: info: CURURI: artists:// Dec 10 22:37:32 volumio volumio[1529]: info: Preload queue cleared Dec 10 22:37:36 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 22:37:36 volumio volumio[1529]: info: CURURI: genres:// Dec 10 22:37:36 volumio volumio[1529]: info: Preload queue cleared Dec 10 22:37:42 volumio volumio[1529]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Dec 10 22:37:42 volumio volumio[1529]: info: Preload queue cleared Dec 10 22:37:42 volumio volumio[1529]: info: Preloading song: http://stream.radioparadise.com/global-flacm Dec 10 22:37:42 volumio volumio[1529]: info: Preloading song: https://stream.radioparadise.com/rock-flacm Dec 10 22:37:42 volumio volumio[1529]: info: Exploding uri http://stream.radioparadise.com/global-flacm in service radio_paradise Dec 10 22:37:42 volumio volumio[1529]: error: [RadioParadise] explodeUri: No station found for uri "http://stream.radioparadise.com/global-flacm" Dec 10 22:37:42 volumio volumio[1529]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 22:37:42 volumio volumio[1529]: TypeError: Cannot read properties of undefined (reading 'then') Dec 10 22:37:42 volumio volumio[1529]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) Dec 10 22:37:42 volumio volumio[1529]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Dec 10 22:37:42 volumio volumio[1529]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Dec 10 22:37:42 volumio volumio[1529]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Dec 10 22:37:42 volumio volumio[1529]: at listOnTimeout (node:internal/timers:573:17) Dec 10 22:37:42 volumio volumio[1529]: at process.processTimers (node:internal/timers:514:7) Dec 10 22:37:42 volumio volumio[1529]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 22:37:42 volumio sudo[3286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-10 22:36' Dec 10 22:37:42 volumio sudo[3286]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"