-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2025-05-31 06:56:58 UTC. -- May 31 06:55:00 unda volumio[6821]: info: May 31 06:55:00 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:00 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:00 unda volumio[6821]: info: May 31 06:55:00 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:00 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:00 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:00 unda volumio[6821]: info: May 31 06:55:00 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:00 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:00 unda volumio[6821]: info: May 31 06:55:00 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:00 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:00 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:00 unda volumio[6821]: info: May 31 06:55:00 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:00 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:00 unda volumio[6821]: info: May 31 06:55:00 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:00 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:00 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:00 unda volumio[6821]: info: ------------------------------ 5ms May 31 06:55:00 unda volumio[6821]: info: sendMpdCommand status took 4 milliseconds May 31 06:55:00 unda volumio[6821]: info: ------------------------------ 4ms May 31 06:55:00 unda volumio[6821]: info: sendMpdCommand status took 3 milliseconds May 31 06:55:00 unda volumio[6821]: info: ------------------------------ 3ms May 31 06:55:00 unda volumio[6821]: info: sendMpdCommand status took 2 milliseconds May 31 06:55:00 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:00 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:00 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:00 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:00 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:00 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:00 unda volumio[6821]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 06:55:00 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:55:00 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:00 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:00 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:00 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":260609,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:00 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:00 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:00 unda volumio[6821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:55:00 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:00 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:00 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:00 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:00 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:00 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":260609,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:00 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:00 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:00 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:00 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:00 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:00 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:00 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:00 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":260609,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:00 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:00 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:00 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:00 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:00 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:00 unda volumio[6821]: info: ------------------------------ 94ms May 31 06:55:00 unda volumio[6821]: info: ------------------------------ 93ms May 31 06:55:00 unda volumio[6821]: info: ------------------------------ 92ms May 31 06:55:00 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:00 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:00 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:00 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:00 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:00 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:10 unda volumio[6821]: info: Disabling plugin rotaryencoder2 May 31 06:55:10 unda volumio[6821]: info: Done. May 31 06:55:16 unda volumio[6821]: info: May 31 06:55:16 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:16 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:16 unda volumio[6821]: info: May 31 06:55:16 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:16 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:16 unda volumio[6821]: info: May 31 06:55:16 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:16 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:16 unda volumio[6821]: info: ------------------------------ 3ms May 31 06:55:16 unda volumio[6821]: info: ------------------------------ 2ms May 31 06:55:16 unda volumio[6821]: info: May 31 06:55:16 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:16 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:16 unda volumio[6821]: info: May 31 06:55:16 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:16 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:16 unda volumio[6821]: info: sendMpdCommand status took 2 milliseconds May 31 06:55:16 unda volumio[6821]: info: sendMpdCommand status took 1 milliseconds May 31 06:55:16 unda volumio[6821]: info: ------------------------------ 1ms May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:16 unda volumio[6821]: info: May 31 06:55:16 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:16 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:16 unda volumio[6821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 06:55:16 unda volumio[6821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 06:55:16 unda volumio[6821]: info: sendMpdCommand status took 1 milliseconds May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:16 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:16 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:16 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:16 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":276863,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:16 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:16 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:16 unda volumio[6821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:55:16 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:16 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:16 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:16 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:16 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:16 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":276863,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:16 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:16 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:16 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:16 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:16 unda volumio[6821]: info: ------------------------------ 53ms May 31 06:55:16 unda volumio[6821]: info: ------------------------------ 54ms May 31 06:55:16 unda volumio[6821]: info: sendMpdCommand playlistinfo took 49 milliseconds May 31 06:55:16 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:55:16 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:16 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:16 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:16 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":276863,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:16 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:16 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:16 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:16 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:16 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:16 unda volumio[6821]: info: ------------------------------ 74ms May 31 06:55:16 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:16 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:16 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:16 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:16 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:16 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:25 unda volumio[6821]: info: May 31 06:55:25 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:25 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:25 unda volumio[6821]: info: May 31 06:55:25 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:25 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:25 unda volumio[6821]: info: May 31 06:55:25 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:25 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:25 unda volumio[6821]: info: May 31 06:55:25 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:25 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:25 unda volumio[6821]: info: ------------------------------ 3ms May 31 06:55:25 unda volumio[6821]: info: sendMpdCommand status took 2 milliseconds May 31 06:55:25 unda volumio[6821]: info: ------------------------------ 2ms May 31 06:55:25 unda volumio[6821]: info: sendMpdCommand status took 2 milliseconds May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:25 unda volumio[6821]: info: May 31 06:55:25 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:25 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:25 unda volumio[6821]: info: May 31 06:55:25 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:25 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:25 unda volumio[6821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 06:55:25 unda volumio[6821]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 06:55:25 unda volumio[6821]: info: ------------------------------ 2ms May 31 06:55:25 unda volumio[6821]: info: sendMpdCommand status took 1 milliseconds May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:25 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:25 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:25 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":285233,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau, Deutschlandfunk-Nachrichtenredaktion","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:25 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:25 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:25 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:25 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:25 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":285233,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau, Deutschlandfunk-Nachrichtenredaktion","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:25 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:25 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:25 unda volumio[6821]: info: ------------------------------ 60ms May 31 06:55:25 unda volumio[6821]: info: ------------------------------ 58ms May 31 06:55:25 unda volumio[6821]: info: sendMpdCommand playlistinfo took 53 milliseconds May 31 06:55:25 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:55:25 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:25 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:25 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":285233,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau, Deutschlandfunk-Nachrichtenredaktion","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:25 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:25 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:25 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:25 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:25 unda volumio[6821]: info: ------------------------------ 83ms May 31 06:55:25 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:25 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:25 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:25 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:25 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:25 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:46 unda volumio[6821]: info: May 31 06:55:46 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:46 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:46 unda volumio[6821]: info: May 31 06:55:46 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:46 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:46 unda volumio[6821]: info: May 31 06:55:46 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:46 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:46 unda volumio[6821]: info: May 31 06:55:46 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:46 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:46 unda volumio[6821]: info: May 31 06:55:46 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:55:46 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:55:46 unda volumio[6821]: info: May 31 06:55:46 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:55:46 unda volumio[6821]: info: ControllerMpd::getState May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:55:46 unda volumio[6821]: info: ------------------------------ 7ms May 31 06:55:46 unda volumio[6821]: info: sendMpdCommand status took 5 milliseconds May 31 06:55:46 unda volumio[6821]: info: ------------------------------ 3ms May 31 06:55:46 unda volumio[6821]: info: sendMpdCommand status took 2 milliseconds May 31 06:55:46 unda volumio[6821]: info: ------------------------------ 3ms May 31 06:55:46 unda volumio[6821]: info: sendMpdCommand status took 2 milliseconds May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:55:46 unda volumio[6821]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 06:55:46 unda volumio[6821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 06:55:46 unda volumio[6821]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:55:46 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:55:46 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:46 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:46 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:46 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":306983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:46 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:46 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:46 unda volumio[6821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:55:46 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:46 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:46 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:46 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:46 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:46 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":306983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:46 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:46 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:46 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:46 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:46 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:55:46 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:55:46 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:55:46 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":306983,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:55:46 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:55:46 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:46 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:46 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:55:46 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:55:46 unda volumio[6821]: info: ------------------------------ 67ms May 31 06:55:46 unda volumio[6821]: info: ------------------------------ 65ms May 31 06:55:46 unda volumio[6821]: info: ------------------------------ 63ms May 31 06:55:46 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:46 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:46 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:46 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:46 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:46 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:55:48 unda volumio[6821]: info: Enabling plugin rotaryencoder2 May 31 06:55:48 unda volumio[6821]: info: Loading plugin "rotaryencoder2"... May 31 06:55:48 unda volumio[6821]: info: PLUGIN START: rotaryencoder2 May 31 06:55:48 unda volumio[6821]: info: [ROTARYENCODER2] loadI18nStrings: /data/plugins/system_hardware/rotaryencoder2/i18n/strings_de.json May 31 06:55:48 unda volumio[6821]: info: [ROTARYENCODER2] loadI18nStrings: loaded: May 31 06:55:48 unda volumio[6821]: info: [ROTARYENCODER2] onStart: Config loaded: May 31 06:55:48 unda volumio[6821]: info: [ROTARYENCODER2] installAllOverlays: 1,2,3 May 31 06:55:48 unda volumio[6821]: info: [ROTARYENCODER2] installAllOverlays: 1,2 May 31 06:55:48 unda volumio[6821]: info: [ROTARYENCODER2] installAllOverlays: 1 May 31 06:55:48 unda volumio[6821]: info: [ROTARYENCODER2] installAllOverlays: May 31 06:55:48 unda volumio[6821]: info: [ROTARYENCODER2] installAllOverlays: end of recursion. May 31 06:55:48 unda volumio[6821]: info: [ROTARYENCODER2] addOverlay for Rotary: 24 23 2 May 31 06:55:48 unda volumio[6821]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 & May 31 06:55:48 unda volumio[6821]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 May 31 06:55:48 unda sudo[7349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:55:48 unda sudo[7349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:49 unda volumio[6821]: info: CoreCommandRouter::volumioGetState May 31 06:55:49 unda kernel: rotary-encoder rotary@18: gray May 31 06:55:49 unda kernel: input: rotary@18 as /devices/platform/rotary@18/input/input21 May 31 06:55:49 unda sudo[7349]: pam_unix(sudo:session): session closed for user root May 31 06:55:49 unda volumio[6821]: info: /dev/input: May 31 06:55:49 unda volumio[6821]: event0 May 31 06:55:49 unda volumio[6821]: mice May 31 06:55:49 unda systemd[1]: Starting Daily apt upgrade and clean activities... May 31 06:55:49 unda systemd-udevd[7357]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 31 06:55:50 unda volumio[6821]: info: [ROTARYENCODER2] addOverlay for Rotary: 5 6 2 May 31 06:55:50 unda volumio[6821]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 & May 31 06:55:50 unda sudo[7407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:55:50 unda sudo[7407]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:50 unda kernel: rotary-encoder rotary@5: gray May 31 06:55:50 unda kernel: input: rotary@5 as /devices/platform/rotary@5/input/input22 May 31 06:55:50 unda sudo[7407]: pam_unix(sudo:session): session closed for user root May 31 06:55:50 unda volumio[6821]: info: /dev/input: May 31 06:55:50 unda volumio[6821]: by-path May 31 06:55:50 unda volumio[6821]: event0 May 31 06:55:50 unda volumio[6821]: event1 May 31 06:55:50 unda volumio[6821]: mice May 31 06:55:50 unda volumio[6821]: /dev/input/by-path: May 31 06:55:50 unda volumio[6821]: platform-rotary@18-event May 31 06:55:50 unda systemd[1]: apt-daily-upgrade.service: Succeeded. May 31 06:55:50 unda systemd[1]: Started Daily apt upgrade and clean activities. May 31 06:55:50 unda systemd-udevd[7357]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 31 06:55:51 unda volumio[6821]: info: [ROTARYENCODER2] addOverlay for Button: 13 true up May 31 06:55:51 unda volumio[6821]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 & May 31 06:55:51 unda sudo[7431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:55:51 unda sudo[7431]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:51 unda sudo[7431]: pam_unix(sudo:session): session closed for user root May 31 06:55:51 unda kernel: gpio-keys button@d: failed to get gpio: -16 May 31 06:55:51 unda kernel: gpio-keys: probe of button@d failed with error -16 May 31 06:55:51 unda volumio[6821]: info: /dev/input: May 31 06:55:51 unda volumio[6821]: by-path May 31 06:55:51 unda volumio[6821]: event0 May 31 06:55:51 unda volumio[6821]: event1 May 31 06:55:51 unda volumio[6821]: mice May 31 06:55:51 unda volumio[6821]: /dev/input/by-path: May 31 06:55:51 unda volumio[6821]: platform-rotary@18-event May 31 06:55:51 unda volumio[6821]: platform-rotary@5-event May 31 06:55:52 unda volumio[6821]: info: [ROTARYENCODER2] addOverlay for Rotary: 17 27 2 May 31 06:55:52 unda volumio[6821]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 & May 31 06:55:52 unda sudo[7442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:55:52 unda sudo[7442]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:52 unda kernel: rotary-encoder rotary@11: gray May 31 06:55:52 unda kernel: input: rotary@11 as /devices/platform/rotary@11/input/input24 May 31 06:55:52 unda sudo[7442]: pam_unix(sudo:session): session closed for user root May 31 06:55:52 unda volumio[6821]: info: /dev/input: May 31 06:55:52 unda volumio[6821]: by-path May 31 06:55:52 unda volumio[6821]: event0 May 31 06:55:52 unda volumio[6821]: event1 May 31 06:55:52 unda volumio[6821]: event2 May 31 06:55:52 unda volumio[6821]: mice May 31 06:55:52 unda volumio[6821]: /dev/input/by-path: May 31 06:55:52 unda volumio[6821]: platform-rotary@18-event May 31 06:55:52 unda volumio[6821]: platform-rotary@5-event May 31 06:55:52 unda systemd-udevd[7357]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 31 06:55:53 unda volumio[6821]: info: [ROTARYENCODER2] addOverlay for Button: 22 true up May 31 06:55:53 unda volumio[6821]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 & May 31 06:55:53 unda sudo[7457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:55:53 unda sudo[7457]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:53 unda kernel: gpio-keys button@16: failed to get gpio: -16 May 31 06:55:53 unda kernel: gpio-keys: probe of button@16 failed with error -16 May 31 06:55:53 unda sudo[7457]: pam_unix(sudo:session): session closed for user root May 31 06:55:53 unda volumio[6821]: info: /dev/input: May 31 06:55:53 unda volumio[6821]: by-path May 31 06:55:53 unda volumio[6821]: event0 May 31 06:55:53 unda volumio[6821]: event1 May 31 06:55:53 unda volumio[6821]: event2 May 31 06:55:53 unda volumio[6821]: mice May 31 06:55:53 unda volumio[6821]: /dev/input/by-path: May 31 06:55:53 unda volumio[6821]: platform-rotary@11-event May 31 06:55:53 unda volumio[6821]: platform-rotary@18-event May 31 06:55:53 unda volumio[6821]: platform-rotary@5-event May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachAllListeners: 1,2,3 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachAllListeners: 1,2 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachAllListeners: 1 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachAllListeners: May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachAllListeners: end of recursion. May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 1 type: rotary May 31 06:55:54 unda sudo[7468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:55:54 unda sudo[7468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:54 unda sudo[7468]: pam_unix(sudo:session): session closed for user root May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=24pinB=23 in Overlays (in load order): May 31 06:55:54 unda volumio[6821]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: rotary 1 uses overlay 0 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachAllListeners: attach rotary 1 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-rotary@18-event, type: rotary May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 1 type: button May 31 06:55:54 unda sudo[7472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:55:54 unda sudo[7472]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:54 unda sudo[7472]: pam_unix(sudo:session): session closed for user root May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=0pinB=undefined in Overlays (in load order): May 31 06:55:54 unda volumio[6821]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: button 1 not using any overlay. May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 2 type: rotary May 31 06:55:54 unda sudo[7475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:55:54 unda sudo[7475]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:54 unda sudo[7475]: pam_unix(sudo:session): session closed for user root May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=5pinB=6 in Overlays (in load order): May 31 06:55:54 unda volumio[6821]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: rotary 2 uses overlay 1 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachAllListeners: attach rotary 2 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-rotary@5-event, type: rotary May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 2 type: button May 31 06:55:54 unda sudo[7479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:55:54 unda sudo[7479]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:54 unda sudo[7479]: pam_unix(sudo:session): session closed for user root May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=13pinB=undefined in Overlays (in load order): May 31 06:55:54 unda volumio[6821]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: button 2 uses overlay 2 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachAllListeners: attach button 2 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-button@d-event, type: button May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 3 type: rotary May 31 06:55:54 unda sudo[7483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:55:54 unda sudo[7483]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:54 unda sudo[7483]: pam_unix(sudo:session): session closed for user root May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=17pinB=27 in Overlays (in load order): May 31 06:55:54 unda volumio[6821]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: rotary 3 uses overlay 3 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachAllListeners: attach rotary 3 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-rotary@11-event, type: rotary May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 3 type: button May 31 06:55:54 unda sudo[7487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:55:54 unda sudo[7487]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:55:54 unda sudo[7487]: pam_unix(sudo:session): session closed for user root May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=22pinB=undefined in Overlays (in load order): May 31 06:55:54 unda volumio[6821]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:55:54 unda volumio[6821]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] checkOverlayExists: button 3 uses overlay 4 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachAllListeners: attach button 3 May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-button@16-event, type: button May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] onStart: Attach Event-handles now. May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] Now addAllEventHandles for rotaries. May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] addEventHandle for rotary: 1 type: rotary May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] addEventHandle for rotary: 2 type: rotary May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] addEventHandle for rotary: 3 type: rotary May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] Now addAllEventHandles for buttons. May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] addEventHandle for rotary: 2 type: button May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] addEventHandle: adding handle : May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] addEventHandle for rotary: 3 type: button May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] addEventHandle: adding handle : May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] onStart: Plugin successfully started. May 31 06:55:54 unda volumio[6821]: info: Done. May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] addEventHandle: Stream from button ended. May 31 06:55:54 unda volumio[6821]: error: [ROTARYENCODER2] addEventHandle: stderr: /bin/cat: /dev/input/by-path/platform-button@16-event: No such file or directory May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("TOAST_WRONG_PARAMETER"):Fehler in Einstellungen May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("TOAST_ERR_FROM_STREAM"):Fehler über den Stream vom Rotary, bitte Hardware und GPIO Settings prüfen. May 31 06:55:54 unda volumio[6821]: info: [ROTARYENCODER2] addEventHandle: child process exited with code 1 May 31 06:56:01 unda volumio[6821]: info: May 31 06:56:01 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:56:01 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:56:01 unda volumio[6821]: info: May 31 06:56:01 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:56:01 unda volumio[6821]: info: ControllerMpd::getState May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:01 unda volumio[6821]: info: May 31 06:56:01 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:56:01 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:56:01 unda volumio[6821]: info: May 31 06:56:01 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:56:01 unda volumio[6821]: info: ControllerMpd::getState May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:01 unda volumio[6821]: info: ------------------------------ 4ms May 31 06:56:01 unda volumio[6821]: info: sendMpdCommand status took 3 milliseconds May 31 06:56:01 unda volumio[6821]: info: ------------------------------ 2ms May 31 06:56:01 unda volumio[6821]: info: sendMpdCommand status took 1 milliseconds May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:01 unda volumio[6821]: info: May 31 06:56:01 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:56:01 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:56:01 unda volumio[6821]: info: May 31 06:56:01 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:56:01 unda volumio[6821]: info: ControllerMpd::getState May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:01 unda volumio[6821]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 06:56:01 unda volumio[6821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 06:56:01 unda volumio[6821]: info: ------------------------------ 2ms May 31 06:56:01 unda volumio[6821]: info: sendMpdCommand status took 1 milliseconds May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:01 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:56:01 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:56:01 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:56:01 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":321106,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:01 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:01 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:01 unda volumio[6821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:01 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:01 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:01 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:56:01 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:56:01 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:56:01 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":321106,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:01 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:01 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:01 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:01 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:01 unda volumio[6821]: info: ------------------------------ 60ms May 31 06:56:01 unda volumio[6821]: info: ------------------------------ 58ms May 31 06:56:01 unda volumio[6821]: info: sendMpdCommand playlistinfo took 53 milliseconds May 31 06:56:01 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:01 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:56:01 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:56:01 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:56:01 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":321106,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:01 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:01 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:01 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:01 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:01 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:01 unda volumio[6821]: info: ------------------------------ 87ms May 31 06:56:01 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:01 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:01 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:01 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:01 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:01 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:03 unda volumio[6821]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getUIConfig: starting: May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getUIConfig: i18nStrings: May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getUIConfig: i18nStringsDefaults: May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getUIConfig: language code: de dir: /data/plugins/system_hardware/rotaryencoder2 May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("VOLUME"):Lautstärke May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("TOGGLEMUTE"):Stumm an/aus May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("PLAYPAUSE"):Pause an/aus May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("DOTS"):... May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("EMIT"):Websocket Nachricht senden May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("EMIT"):Websocket Nachricht senden May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("DOTS"):... May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("EMIT"):Websocket Nachricht senden May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("SKIP"):Titel vor/zurück May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("PLAYPAUSE"):Pause an/aus May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("RANDOM"):Zufällige Wiedergabe May 31 06:56:03 unda volumio[6821]: info: [ROTARYENCODER2] getI18nString("TOGGLEMUTE"):Stumm an/aus May 31 06:56:09 unda volumio[6821]: info: CoreCommandRouter::volumioGetState May 31 06:56:11 unda volumio[6821]: info: May 31 06:56:11 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:56:11 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:56:11 unda volumio[6821]: info: May 31 06:56:11 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:56:11 unda volumio[6821]: info: ControllerMpd::getState May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:11 unda volumio[6821]: info: May 31 06:56:11 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:56:11 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:56:11 unda volumio[6821]: info: May 31 06:56:11 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:56:11 unda volumio[6821]: info: ControllerMpd::getState May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:11 unda volumio[6821]: info: ------------------------------ 3ms May 31 06:56:11 unda volumio[6821]: info: sendMpdCommand status took 3 milliseconds May 31 06:56:11 unda volumio[6821]: info: ------------------------------ 2ms May 31 06:56:11 unda volumio[6821]: info: sendMpdCommand status took 2 milliseconds May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:11 unda volumio[6821]: info: May 31 06:56:11 unda volumio[6821]: ---------------------------- MPD announces system playlist update May 31 06:56:11 unda volumio[6821]: info: Ignoring MPD Status Update May 31 06:56:11 unda volumio[6821]: info: May 31 06:56:11 unda volumio[6821]: ---------------------------- MPD announces state update: player May 31 06:56:11 unda volumio[6821]: info: ControllerMpd::getState May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:11 unda volumio[6821]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 06:56:11 unda volumio[6821]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 06:56:11 unda volumio[6821]: info: ------------------------------ 2ms May 31 06:56:11 unda volumio[6821]: info: sendMpdCommand status took 1 milliseconds May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::parseState May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:11 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:56:11 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:56:11 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":331237,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau, Deutschlandfunk-Nachrichtenredaktion","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:11 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:11 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:11 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:56:11 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:56:11 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":331237,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau, Deutschlandfunk-Nachrichtenredaktion","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:11 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:11 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:11 unda volumio[6821]: info: ------------------------------ 67ms May 31 06:56:11 unda volumio[6821]: info: ------------------------------ 66ms May 31 06:56:11 unda volumio[6821]: info: sendMpdCommand playlistinfo took 61 milliseconds May 31 06:56:11 unda volumio[6821]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:11 unda volumio[6821]: info: ControllerMpd::pushState May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::servicePushState May 31 06:56:11 unda volumio[6821]: info: CorePlayQueue::getTrack 0 May 31 06:56:11 unda volumio[6821]: verbose: STATE SERVICE {"status":"play","position":0,"seek":331237,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau, Deutschlandfunk-Nachrichtenredaktion","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:11 unda volumio[6821]: verbose: CURRENT POSITION 0 May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::syncState stateService play May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:11 unda volumio[6821]: info: Received an update from plugin. extracting info from payload May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:11 unda volumio[6821]: info: CoreStateMachine::pushState May 31 06:56:11 unda volumio[6821]: info: CoreCommandRouter::volumioPushState May 31 06:56:11 unda volumio[6821]: info: ------------------------------ 89ms May 31 06:56:11 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:11 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:11 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:11 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:11 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:11 unda volumio[6821]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:18 unda sudo[7494]: volumio : TTY=pts/1 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl stop volumio.service May 31 06:56:18 unda sudo[7494]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) May 31 06:56:18 unda systemd[1]: Stopping Volumio Backend Module... May 31 06:56:18 unda go-librespot[6974]: time="2025-05-31T06:56:18Z" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" May 31 06:56:18 unda volumio-remote-updater[612]: [2025-05-31 06:56:18] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 31 06:56:18 unda volumio-remote-updater[612]: [2025-05-31 06:56:18] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 31 06:56:18 unda systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM May 31 06:56:18 unda systemd[1]: volumio.service: Succeeded. May 31 06:56:18 unda systemd[1]: Stopped Volumio Backend Module. May 31 06:56:18 unda sudo[7494]: pam_unix(sudo:session): session closed for user root May 31 06:56:18 unda sudo[7497]: volumio : TTY=pts/1 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl start volumio.service May 31 06:56:18 unda sudo[7497]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) May 31 06:56:18 unda systemd[1]: Started dynamicswap service. May 31 06:56:18 unda systemd[1]: Started Volumio Backend Module. May 31 06:56:18 unda sudo[7497]: pam_unix(sudo:session): session closed for user root May 31 06:56:18 unda systemd[1]: dynamicswap.service: Succeeded. May 31 06:56:19 unda volumio[7504]: info: ------------------------------------------- May 31 06:56:19 unda volumio[7504]: info: ----- Volumio3 ---- May 31 06:56:19 unda volumio[7504]: info: ------------------------------------------- May 31 06:56:19 unda volumio[7504]: info: ----- System startup ---- May 31 06:56:19 unda volumio[7504]: info: ------------------------------------------- May 31 06:56:20 unda volumio[7504]: info: MYVOLUMIO Environment detected May 31 06:56:20 unda volumio[7504]: info: Plugin folders cleanup May 31 06:56:20 unda volumio[7504]: info: Scanning into folder /volumio/app/plugins/ May 31 06:56:20 unda volumio[7504]: info: Scanning category audio_interface May 31 06:56:20 unda volumio[7504]: info: Scanning category miscellanea May 31 06:56:20 unda volumio[7504]: info: Scanning category music_service May 31 06:56:20 unda volumio[7504]: info: Scanning category plugins.json May 31 06:56:20 unda volumio[7504]: info: Scanning category system_controller May 31 06:56:20 unda volumio[7504]: info: Scanning category user_interface May 31 06:56:20 unda volumio[7504]: info: Scanning into folder /data/plugins/ May 31 06:56:20 unda volumio[7504]: info: Scanning category music_service May 31 06:56:20 unda volumio[7504]: info: Scanning category system_controller May 31 06:56:20 unda volumio[7504]: info: Scanning category system_hardware May 31 06:56:20 unda volumio[7504]: info: Plugin folders cleanup completed May 31 06:56:20 unda volumio[7504]: info: ------------------------------------------- May 31 06:56:20 unda volumio[7504]: info: ----- Core plugins startup ---- May 31 06:56:20 unda volumio[7504]: info: ------------------------------------------- May 31 06:56:20 unda volumio[7504]: info: Loading plugins from folder /volumio/app/plugins/ May 31 06:56:20 unda volumio[7504]: info: Adding plugin upnp to MyMusic Plugins May 31 06:56:20 unda volumio[7504]: info: Adding plugin airplay_emulation to MyMusic Plugins May 31 06:56:20 unda volumio[7504]: info: Adding plugin upnp_browser to MyMusic Plugins May 31 06:56:20 unda volumio[7504]: info: Loading plugins from folder /data/plugins/ May 31 06:56:20 unda volumio[7504]: info: Loading plugin "system"... May 31 06:56:20 unda volumio[7504]: info: Loading plugin "appearance"... May 31 06:56:20 unda volumio[7504]: info: Loading plugin "network"... May 31 06:56:20 unda volumio[7504]: info: Refreshing Cached IP Addresses May 31 06:56:20 unda sudo[7530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 06:56:20 unda sudo[7530]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:20 unda sudo[7530]: pam_unix(sudo:session): session closed for user root May 31 06:56:20 unda sudo[7532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 06:56:20 unda volumio[7504]: info: Loading plugin "services"... May 31 06:56:20 unda sudo[7532]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:20 unda volumio[7504]: info: Loading plugin "alsa_controller"... May 31 06:56:20 unda sudo[7532]: pam_unix(sudo:session): session closed for user root May 31 06:56:20 unda sudo[7535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 06:56:20 unda sudo[7535]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:20 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 06:56:20 unda volumio[7504]: info: Loading plugin "wizard"... May 31 06:56:20 unda volumio[7504]: info: Loading plugin "networkfs"... May 31 06:56:20 unda volumio[7504]: info: Cannot mount NAS diskstation at system boot, trial number 1 ,retrying in 5 seconds May 31 06:56:20 unda volumio[7504]: info: Starting Udev Watcher for removable devices May 31 06:56:20 unda volumio[7504]: info: Ignoring mount for partition: boot May 31 06:56:20 unda volumio[7504]: info: Ignoring mount for partition: volumio May 31 06:56:20 unda volumio[7504]: info: Ignoring mount for partition: volumio_data May 31 06:56:20 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 06:56:20 unda volumio[7504]: info: Loading plugin "volumio_command_line_client"... May 31 06:56:20 unda volumio[7504]: info: Loading plugin "upnp"... May 31 06:56:20 unda volumio[7504]: info: [1748674580893] Starting Upmpd Daemon May 31 06:56:20 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 06:56:20 unda volumio[7504]: info: Loading plugin "my_music"... May 31 06:56:20 unda volumio[7504]: info: Loading plugin "mpd"... May 31 06:56:21 unda volumio[7504]: info: Loading plugin "upnp_browser"... May 31 06:56:21 unda volumio[7504]: info: Loading plugin "alarm-clock"... May 31 06:56:21 unda volumio[7504]: info: Loading plugin "airplay_emulation"... May 31 06:56:21 unda volumio[7504]: info: Starting Shairport Sync May 31 06:56:21 unda volumio[7504]: info: Loading plugin "last_100"... May 31 06:56:21 unda volumio[7504]: info: Loading plugin "webradio"... May 31 06:56:21 unda volumio[7504]: info: Loading plugin "i2s_dacs"... May 31 06:56:21 unda volumio[7504]: info: Loading plugin "volumiodiscovery"... May 31 06:56:21 unda volumio[7504]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 06:56:21 unda volumio[7504]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 06:56:21 unda volumio[7504]: *** WARNING *** For more information see May 31 06:56:21 unda node[7504]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 06:56:21 unda volumio[7504]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 06:56:21 unda volumio[7504]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 06:56:21 unda volumio[7504]: *** WARNING *** For more information see May 31 06:56:21 unda node[7504]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 06:56:21 unda node[7504]: *** WARNING *** For more information see May 31 06:56:21 unda node[7504]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 06:56:21 unda node[7504]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 06:56:21 unda node[7504]: *** WARNING *** For more information see May 31 06:56:21 unda volumio[7504]: info: Applying required configuration parameters for plugin volumiodiscovery May 31 06:56:21 unda volumio[7504]: info: Discovery: Started advertising with name: Unda May 31 06:56:21 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 06:56:21 unda volumio[7504]: info: Loading plugin "spop"... May 31 06:56:22 unda volumio[7504]: info: Loading plugin "outputs"... May 31 06:56:22 unda volumio[7504]: info: Loading plugin "albumart"... May 31 06:56:22 unda volumio[7504]: info: Plugin example_plugin is not enabled May 31 06:56:22 unda volumio[7504]: info: Loading plugin "inputs"... May 31 06:56:22 unda volumio[7504]: info: Loading plugin "updater_comm"... May 31 06:56:22 unda volumio[7504]: info: Plugin mpdemulation is not enabled May 31 06:56:22 unda volumio[7504]: info: Loading plugin "rest_api"... May 31 06:56:22 unda volumio[7504]: info: Loading plugin "websocket"... May 31 06:56:22 unda volumio[7504]: info: Starting Socket.io Server version 2.3.0 May 31 06:56:22 unda volumio[7504]: info: Loading plugin "backup_restore"... May 31 06:56:22 unda volumio[7504]: Forking 3 albumart workers May 31 06:56:22 unda volumio[7504]: info: Applying required configuration parameters for plugin backup_restore May 31 06:56:22 unda volumio[7504]: info: Loading plugin "eadog_lcd"... May 31 06:56:22 unda volumio[7504]: info: Applying required configuration parameters for plugin eadog_lcd May 31 06:56:22 unda volumio[7504]: info: Loading plugin "rotaryencoder2"... May 31 06:56:23 unda volumio[7504]: info: Loading i18n strings for locale de May 31 06:56:23 unda volumio[7504]: Updating browse sources language May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 06:56:23 unda volumio[7504]: Starting albumart workers May 31 06:56:23 unda volumio[7504]: Starting albumart workers May 31 06:56:23 unda volumio[7504]: Starting albumart workers May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::initPlayerControls May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:56:23 unda volumio[7504]: Express server listening on port 3000 May 31 06:56:23 unda volumio[7504]: [Metrics] WebUI: 3s 859.41ms May 31 06:56:23 unda volumio[7504]: info: CoreStateMachine::resetVolumioState May 31 06:56:23 unda volumio[7504]: info: CoreStateMachine::getcurrentVolume May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::volumioRetrievevolume May 31 06:56:23 unda volumio-remote-updater[612]: [2025-05-31 06:56:23] [connect] Successful connection May 31 06:56:23 unda volumio[7504]: verbose: New Socket.io Connection to 192.168.178.76 from 192.168.178.23 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 May 31 06:56:23 unda volumio[7504]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 31 06:56:23 unda volumio[7504]: verbose: New Socket.io Connection to 192.168.178.76 from 192.168.178.23 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 May 31 06:56:23 unda volumio[7504]: verbose: New Socket.io Connection to 192.168.178.76:3000 from 192.168.178.64 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:23 unda volumio[7504]: info: Reloading queue from file May 31 06:56:23 unda volumio-remote-updater[612]: [2025-05-31 06:56:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748674583 101 May 31 06:56:23 unda volumio[7504]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 May 31 06:56:23 unda volumio[7504]: info: CoreStateMachine::setRepeat false single undefined May 31 06:56:23 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:23 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:23 unda volumio[7504]: info: CoreStateMachine::setRandom true May 31 06:56:23 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:23 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:23 unda volumio[7504]: info: Setting Device type: Raspberry PI May 31 06:56:23 unda volumio[7504]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 31 06:56:23 unda volumio[7504]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 May 31 06:56:23 unda volumio[7504]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 31 06:56:23 unda volumio[7504]: info: Completed loading Core Plugins May 31 06:56:23 unda volumio[7504]: info: Preparing to generate the ALSA configuration file May 31 06:56:23 unda volumio[7504]: info: VolumeController:: Volume=64 Mute =false May 31 06:56:23 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:23 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:23 unda volumio[7504]: info: CoreStateMachine::updateTrackBlock May 31 06:56:23 unda volumio[7504]: info: CorePlayQueue::getTrackBlock May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::volumioRetrievevolume May 31 06:56:23 unda volumio[7504]: info: Asound.conf file unchanged, so no further update is needed May 31 06:56:23 unda volumio[7504]: info: Output device has changed, restarting MPD May 31 06:56:23 unda sudo[7601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 06:56:23 unda sudo[7601]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:23 unda volumio[7504]: info: Output device has changed, restarting Shairport Sync May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:23 unda sudo[7601]: pam_unix(sudo:session): session closed for user root May 31 06:56:23 unda sudo[7612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 06:56:23 unda sudo[7612]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:23 unda systemd[1]: Stopping Music Player Daemon... May 31 06:56:23 unda volumio[7504]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 06:56:23 unda volumio[7504]: info: ___________ START PLUGINS ___________ May 31 06:56:23 unda volumio[7504]: info: ControllerMpd::onStart: Initializing MPD May 31 06:56:23 unda volumio[7504]: info: Creating MPD Configuration file May 31 06:56:23 unda sudo[7618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 06:56:23 unda sudo[7618]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:23 unda systemd[1]: mpd.service: Succeeded. May 31 06:56:23 unda systemd[1]: Stopped Music Player Daemon. May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 06:56:23 unda sudo[7618]: pam_unix(sudo:session): session closed for user root May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 06:56:23 unda volumio[7504]: info: [1748674583791] CoreMusicLibrary::Adding element Medienserver May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 06:56:23 unda sudo[7621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 06:56:23 unda sudo[7621]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:23 unda volumio[7504]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:23 unda systemd[1]: Starting Music Player Daemon... May 31 06:56:23 unda systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 31 06:56:23 unda systemd[1]: mpd.service: Succeeded. May 31 06:56:23 unda systemd[1]: Stopped Music Player Daemon. May 31 06:56:23 unda systemd[1]: Starting Music Player Daemon... May 31 06:56:23 unda volumio[7504]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 06:56:23 unda volumio[7504]: info: [1748674583898] CoreMusicLibrary::Adding element Last_100 May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 06:56:23 unda volumio[7504]: info: [1748674583901] CoreMusicLibrary::Adding element Webradio May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 06:56:23 unda volumio[7504]: info: Initializing BBC Radios May 31 06:56:23 unda sudo[7535]: pam_unix(sudo:session): session closed for user root May 31 06:56:23 unda sudo[7631]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 06:56:23 unda sudo[7631]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:23 unda sudo[7631]: pam_unix(sudo:session): session closed for user root May 31 06:56:23 unda volumio[7504]: info: Creating Spotify config file May 31 06:56:23 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] loadI18nStrings: /data/plugins/system_hardware/rotaryencoder2/i18n/strings_de.json May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] loadI18nStrings: loaded: May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] onStart: Config loaded: May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] installAllOverlays: 1,2,3 May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] installAllOverlays: 1,2 May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] installAllOverlays: 1 May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] installAllOverlays: May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] installAllOverlays: end of recursion. May 31 06:56:24 unda volumio[7504]: info: Volumio Calling Home May 31 06:56:24 unda volumio[7504]: load module onoff May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] addOverlay for Rotary: 24 23 2 May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 & May 31 06:56:24 unda sudo[7649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:56:24 unda sudo[7649]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:24 unda sudo[7649]: pam_unix(sudo:session): session closed for user root May 31 06:56:24 unda volumio[7504]: error: [ROTARYENCODER2] addOverlay: * Failed to apply overlay '5_rotary-encoder' (kernel) May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("TOAST_WRONG_PARAMETER"):Fehler in Einstellungen May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("TOAST_ERR_ADD_OVERLAY_FAILED"):Hinzufügen gescheitert für Overlay: May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] addOverlay for Rotary: 5 6 2 May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 & May 31 06:56:24 unda volumio[7504]: info: MPD Permissions set May 31 06:56:24 unda volumio[7504]: info: MPD Permissions set May 31 06:56:24 unda sudo[7658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:56:24 unda sudo[7658]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:24 unda volumio[7504]: info: Discovery: adding 153ab1b8-7d66-4115-84da-8d31d7c571ea May 31 06:56:24 unda volumio[7504]: info: Discovery: Found device Unda May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:24 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:24 unda sudo[7658]: pam_unix(sudo:session): session closed for user root May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 31 06:56:24 unda volumio[7504]: info: Volumio called home May 31 06:56:24 unda volumio[7504]: info: Spotify config file written May 31 06:56:24 unda volumio[7504]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 31 06:56:24 unda sudo[7668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 31 06:56:24 unda volumio[7504]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 31 06:56:24 unda sudo[7668]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:24 unda volumio[7504]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 31 06:56:24 unda volumio[7504]: info: VolumeController:: Volume=64 Mute =false May 31 06:56:24 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:24 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:24 unda volumio[7504]: error: [ROTARYENCODER2] addOverlay: * Failed to apply overlay '5_rotary-encoder' (kernel) May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("TOAST_WRONG_PARAMETER"):Fehler in Einstellungen May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("TOAST_ERR_ADD_OVERLAY_FAILED"):Hinzufügen gescheitert für Overlay: May 31 06:56:24 unda systemd[1]: Stopping go-librespot Daemon... May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] addOverlay for Rotary: 17 27 2 May 31 06:56:24 unda systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 31 06:56:24 unda systemd[1]: go-librespot-daemon.service: Succeeded. May 31 06:56:24 unda systemd[1]: Stopped go-librespot Daemon. May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] addOverlay: executing command: /usr/bin/sudo /usr/bin/dtoverlay rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 & May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda sudo[7672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:56:24 unda sudo[7672]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:24 unda systemd[1]: Started go-librespot Daemon. May 31 06:56:24 unda go-librespot[7670]: Librespot-go daemon starting... May 31 06:56:24 unda sudo[7668]: pam_unix(sudo:session): session closed for user root May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda go-librespot[7670]: time="2025-05-31T06:56:24Z" level=info msg="generated new device id: bc690a2597001cda1fa847615bc9a15e5429f75d" May 31 06:56:24 unda go-librespot[7670]: time="2025-05-31T06:56:24Z" level=debug msg="stored credentials found for 31cyexhwrczzgfjbp5dlqd77vtk4" May 31 06:56:24 unda sudo[7672]: pam_unix(sudo:session): session closed for user root May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:24 unda volumio[7504]: info: Discovery: adding 6106a98f-8142-4909-87c1-594ab15d4cd5 May 31 06:56:24 unda volumio[7504]: info: Discovery: Found device Volumio May 31 06:56:24 unda volumio[7504]: info: Discovery: Connecting to remote: 192.168.178.64 May 31 06:56:24 unda volumio[7504]: info: No need to fix Spotify hosts May 31 06:56:24 unda go-librespot[7670]: time="2025-05-31T06:56:24Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 31 06:56:24 unda go-librespot[7670]: time="2025-05-31T06:56:24Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 31 06:56:24 unda go-librespot[7670]: time="2025-05-31T06:56:24Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 31 06:56:24 unda go-librespot[7670]: time="2025-05-31T06:56:24Z" level=debug msg="zeroconf server listening on port 37129" May 31 06:56:24 unda volumio[7504]: error: [ROTARYENCODER2] addOverlay: * Failed to apply overlay '5_rotary-encoder' (kernel) May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("TOAST_WRONG_PARAMETER"):Fehler in Einstellungen May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("TOAST_ERR_ADD_OVERLAY_FAILED"):Hinzufügen gescheitert für Overlay: May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] attachAllListeners: 1,2,3 May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] attachAllListeners: 1,2 May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] attachAllListeners: 1 May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] attachAllListeners: May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] attachAllListeners: end of recursion. May 31 06:56:24 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 1 type: rotary May 31 06:56:24 unda sudo[7697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:56:24 unda sudo[7697]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:24 unda go-librespot[7670]: time="2025-05-31T06:56:24Z" level=debug msg="obtained new client token: AACjvkSSxnMupnZDVwaPkDRk0ftivnucygDE5OFqLoCPJqGar6dmI+lT1iswRs/XmP1OVh/L9Fdjfnm5Vrdp7PDh/fujwuoIKX5kW1CPcZuD4fhDk1qPJThc9V+7ViiXtjYfZ5jVKr2OhWopPMXq3gMKSEewFBXS6soa2MFwUfNKXmbict2SEoW4ef1wo0lyBk3Hsj/6u58taN7nz1DIAfOJjkcKYKqGpExzXscRdybYFHYlR3X1oZb9Lwvd1A==" May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:24 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:24 unda sudo[7697]: pam_unix(sudo:session): session closed for user root May 31 06:56:24 unda volumio[7504]: info: Starting Shairport Sync May 31 06:56:24 unda mpd[7643]: May 31 06:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 06:56:24 unda volumio[7504]: info: Starting Shairport Sync May 31 06:56:24 unda sudo[7700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 06:56:24 unda sudo[7700]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:24 unda volumio[7504]: info: Starting Shairport Sync May 31 06:56:24 unda go-librespot[7670]: time="2025-05-31T06:56:24Z" level=info msg="connected to ap-gew4.spotify.com:4070" May 31 06:56:24 unda systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 06:56:24 unda systemd[1]: shairport-sync.service: Succeeded. May 31 06:56:24 unda sudo[7703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 06:56:24 unda systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 06:56:24 unda sudo[7703]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:24 unda volumio[7504]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 31 06:56:24 unda volumio[7504]: SPOTIFY: BQD_yM6u0v51qDmmlriYHjFCnFyoxKRYbj5hh1luv9003GsI4SuyhggFBGHk0Q5xIt4drm5wBO6iNYOu3G0RgvJmeMQ1GjGMPaU4FcCHy5ObZStnuCUUnb6GKUc1GHOr69yoD7QSls6JP_wGS235fHf-qgq81u5hb7ely9wfMArh0O-NSbd6ZcQWBA0JUGSa4PO_JedRzNnm_C8-n5PtRHVx_4jnOg6A5UPBFw2HysQ8ix3hrmMB-SKVPB8hrykRy6uUw9PVcaoU058TTpwc9BzPDCQfzFoKhru5nJRhBIW3GUtqehKld4JsugcjtQ May 31 06:56:24 unda volumio[7504]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 31 06:56:24 unda volumio[7504]: info: New Spotify access token = BQD_yM6u0v51qDmmlriYHjFCnFyoxKRYbj5hh1luv9003GsI4SuyhggFBGHk0Q5xIt4drm5wBO6iNYOu3G0RgvJmeMQ1GjGMPaU4FcCHy5ObZStnuCUUnb6GKUc1GHOr69yoD7QSls6JP_wGS235fHf-qgq81u5hb7ely9wfMArh0O-NSbd6ZcQWBA0JUGSa4PO_JedRzNnm_C8-n5PtRHVx_4jnOg6A5UPBFw2HysQ8ix3hrmMB-SKVPB8hrykRy6uUw9PVcaoU058TTpwc9BzPDCQfzFoKhru5nJRhBIW3GUtqehKld4JsugcjtQ May 31 06:56:24 unda volumio[7504]: info: Spotify credentials grant success - running version from March 24, 2019 May 31 06:56:24 unda go-librespot[7670]: time="2025-05-31T06:56:24Z" level=debug msg="completed keyexchange" May 31 06:56:24 unda sudo[7706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 06:56:24 unda sudo[7706]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:24 unda systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:24 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:24 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:24 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:24 unda volumio[7504]: info: Discovery: Connected to remote: 192.168.178.64 May 31 06:56:24 unda sudo[7700]: pam_unix(sudo:session): session closed for user root May 31 06:56:24 unda systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 06:56:24 unda systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 31 06:56:24 unda systemd[1]: shairport-sync.service: Succeeded. May 31 06:56:25 unda systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 06:56:25 unda systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 06:56:25 unda sudo[7703]: pam_unix(sudo:session): session closed for user root May 31 06:56:25 unda sudo[7706]: pam_unix(sudo:session): session closed for user root May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=24pinB=23 in Overlays (in load order): May 31 06:56:25 unda volumio[7504]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: rotary 1 uses overlay 0 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] attachAllListeners: attach rotary 1 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-rotary@18-event, type: rotary May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 1 type: button May 31 06:56:25 unda sudo[7716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:56:25 unda sudo[7716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:25 unda volumio[7504]: info: Shairport-Sync Started May 31 06:56:25 unda volumio[7504]: Error adding Membership: Error: addMembership EINVAL May 31 06:56:25 unda volumio[7504]: info: Shairport-Sync Started May 31 06:56:25 unda volumio[7504]: info: Shairport-Sync Started May 31 06:56:25 unda sudo[7716]: pam_unix(sudo:session): session closed for user root May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=0pinB=undefined in Overlays (in load order): May 31 06:56:25 unda volumio[7504]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: button 1 not using any overlay. May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 2 type: rotary May 31 06:56:25 unda sudo[7719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:56:25 unda sudo[7719]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:25 unda sudo[7719]: pam_unix(sudo:session): session closed for user root May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=5pinB=6 in Overlays (in load order): May 31 06:56:25 unda volumio[7504]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: rotary 2 uses overlay 1 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] attachAllListeners: attach rotary 2 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-rotary@5-event, type: rotary May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 2 type: button May 31 06:56:25 unda sudo[7723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:56:25 unda sudo[7723]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:25 unda sudo[7723]: pam_unix(sudo:session): session closed for user root May 31 06:56:25 unda volumio[7504]: SPOTIFY: User informations: {"country":"DE","display_name":"Thomstie","email":"spotify@thomas-rohleder.de","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31cyexhwrczzgfjbp5dlqd77vtk4"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31cyexhwrczzgfjbp5dlqd77vtk4","id":"31cyexhwrczzgfjbp5dlqd77vtk4","images":[],"product":"premium","type":"user","uri":"spotify:user:31cyexhwrczzgfjbp5dlqd77vtk4"} May 31 06:56:25 unda volumio[7504]: info: Spotify Successfully logged in May 31 06:56:25 unda volumio[7504]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 06:56:25 unda volumio[7504]: info: [1748674585249] CoreMusicLibrary::Adding element Spotify May 31 06:56:25 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 06:56:25 unda volumio[7504]: Cannot find translation for source Spotify May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=13pinB=undefined in Overlays (in load order): May 31 06:56:25 unda volumio[7504]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: button 2 uses overlay 2 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] attachAllListeners: attach button 2 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-button@d-event, type: button May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 3 type: rotary May 31 06:56:25 unda sudo[7727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:56:25 unda sudo[7727]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:25 unda sudo[7727]: pam_unix(sudo:session): session closed for user root May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=17pinB=27 in Overlays (in load order): May 31 06:56:25 unda volumio[7504]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: rotary 3 uses overlay 3 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] attachAllListeners: attach rotary 3 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-rotary@11-event, type: rotary May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 3 type: button May 31 06:56:25 unda systemd[1]: Started Music Player Daemon. May 31 06:56:25 unda sudo[7612]: pam_unix(sudo:session): session closed for user root May 31 06:56:25 unda sudo[7621]: pam_unix(sudo:session): session closed for user root May 31 06:56:25 unda sudo[7731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 31 06:56:25 unda sudo[7731]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:25 unda sudo[7731]: pam_unix(sudo:session): session closed for user root May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=22pinB=undefined in Overlays (in load order): May 31 06:56:25 unda volumio[7504]: 0: rotary-encoder pin_a=24 pin_b=23 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 1: rotary-encoder pin_a=5 pin_b=6 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 2: gpio-key gpio=13 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: 3: rotary-encoder pin_a=17 pin_b=27 relative_axis=true steps-per-period=2 May 31 06:56:25 unda volumio[7504]: 4: gpio-key gpio=22 active_low=1 gpio_pull=up keycode=20 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] checkOverlayExists: button 3 uses overlay 4 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] attachAllListeners: attach button 3 May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] attachListener: path: /dev/input/by-path/platform-button@16-event, type: button May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] onStart: Attach Event-handles now. May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] Now addAllEventHandles for rotaries. May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle for rotary: 1 type: rotary May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle for rotary: 2 type: rotary May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle for rotary: 3 type: rotary May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="completed challenge" May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] Now addAllEventHandles for buttons. May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle for rotary: 2 type: button May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle: adding handle : May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle for rotary: 3 type: button May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle: adding handle : May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] onStart: Plugin successfully started. May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle: Stream from button ended. May 31 06:56:25 unda volumio[7504]: error: [ROTARYENCODER2] addEventHandle: stderr: /bin/cat: /dev/input/by-path/platform-button@16-event: No such file or directory May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("TOAST_WRONG_PARAMETER"):Fehler in Einstellungen May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("TOAST_ERR_FROM_STREAM"):Fehler über den Stream vom Rotary, bitte Hardware und GPIO Settings prüfen. May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="authenticated as 31cyexhwrczzgfjbp5dlqd77vtk4" May 31 06:56:25 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle: child process exited with code 1 May 31 06:56:25 unda volumio[7504]: error: MPD error: The expression evaluated to a falsy value: May 31 06:56:25 unda volumio[7504]: assert.ok(self.idling) May 31 06:56:25 unda volumio[7504]: error: The expression evaluated to a falsy value: May 31 06:56:25 unda volumio[7504]: assert.ok(self.idling) May 31 06:56:25 unda volumio[7504]: info: MPD running with PID7643 May 31 06:56:25 unda volumio[7504]: ,establishing connection May 31 06:56:25 unda volumio[7504]: error: updateQueue error: null May 31 06:56:25 unda volumio[7504]: error: updateQueue error: null May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="authenticated as 31cyexhwrczzgfjbp5dlqd77vtk4" May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="dealer connection opened" May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="initializing zeroconf session, username: 31cyexhwrczzgfjbp5dlqd77vtk4" May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="autoplay enabled: false" May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="received connection id: MmZlODQ3NGQtNjMzYS00ZTkyLTk5ZDctMjM4ZGQzMzk4OGQ5K2RlYWxlcit0Y3A6Ly8wYWNhNWE1ZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRTU5OTI5RjkwOTExREQxM0E5NzBDQUQ3QzU1NkZEQzRFOEY3QTUxMUQwMURFMjRCNjZBRDQwMkJBMkM0QTVBMA==" May 31 06:56:25 unda go-librespot[7670]: time="2025-05-31T06:56:25Z" level=debug msg="put connect state because NEW_DEVICE" May 31 06:56:25 unda volumio[7504]: info: Cannot mount NAS diskstation at system boot, trial number 2 ,retrying in 5 seconds May 31 06:56:25 unda volumio[7504]: load module onoff May 31 06:56:25 unda volumio[7504]: info: Completed starting Core Plugins May 31 06:56:25 unda volumio[7504]: info: ------------------------------------------- May 31 06:56:25 unda volumio[7504]: info: ----- MyVolumio plugins startup ---- May 31 06:56:25 unda volumio[7504]: info: ------------------------------------------- May 31 06:56:25 unda volumio[7504]: info: [MyVolumio PluginManager] Fetching plans data.... May 31 06:56:27 unda volumio[7504]: verbose: New Socket.io Connection to 192.168.178.76 from 192.168.178.23 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::volumioGetVisibleSources May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:27 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:27 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 06:56:27 unda volumio[7504]: info: Received Get System Info May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 06:56:27 unda volumio[7504]: info: Discovery: Getting this device information May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:27 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:27 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:27 unda volumio[7504]: info: Listing playlists May 31 06:56:27 unda volumio[7504]: info: go-librespot daemon successfully initialized May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 31 06:56:27 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:27 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:28 unda volumio[7504]: info: Preload queue cleared May 31 06:56:28 unda volumio[7504]: info: CoreCommandRouter::volumioPlay May 31 06:56:28 unda volumio[7504]: info: CoreStateMachine::play index undefined May 31 06:56:28 unda volumio[7504]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 06:56:28 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:28 unda volumio[7504]: info: CoreStateMachine::startPlaybackTimer May 31 06:56:28 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:28 unda volumio[7504]: info: [1748674588951] ControllerWebradio::clearAddPlayTrack May 31 06:56:28 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand stop May 31 06:56:28 unda volumio[7504]: info: sendMpdCommand stop took 1 milliseconds May 31 06:56:28 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand clear May 31 06:56:28 unda volumio[7504]: info: May 31 06:56:28 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:28 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:28 unda volumio[7504]: info: sendMpdCommand clear took 2 milliseconds May 31 06:56:28 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand load "https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein" May 31 06:56:28 unda volumio[7504]: info: May 31 06:56:28 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:28 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:28 unda volumio[7504]: info: May 31 06:56:28 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:28 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:28 unda volumio[7504]: error: updateQueue error: null May 31 06:56:28 unda volumio[7504]: info: ------------------------------ 6ms May 31 06:56:30 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand add "https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein" May 31 06:56:30 unda volumio[7504]: info: Cannot mount NAS diskstation at system boot, trial number 3 ,retrying in 5 seconds May 31 06:56:30 unda volumio[7504]: info: Initializing connection to go-librespot Websocket May 31 06:56:30 unda go-librespot[7670]: time="2025-05-31T06:56:30Z" level=debug msg="new websocket client" May 31 06:56:30 unda volumio[7504]: info: Connection to go-librespot Websocket established May 31 06:56:30 unda sudo[7742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 06:56:30 unda sudo[7742]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:30 unda sudo[7742]: pam_unix(sudo:session): session closed for user root May 31 06:56:30 unda sudo[7745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 06:56:30 unda sudo[7745]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:30 unda sudo[7745]: pam_unix(sudo:session): session closed for user root May 31 06:56:30 unda sudo[7748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 31 06:56:30 unda sudo[7748]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:30 unda sudo[7748]: pam_unix(sudo:session): session closed for user root May 31 06:56:30 unda volumio[7504]: info: Upmpdcli Daemon Started May 31 06:56:31 unda volumio[7504]: error: updateQueue error: null May 31 06:56:31 unda volumio[7504]: error: updateQueue error: null May 31 06:56:31 unda volumio[7504]: info: ------------------------------ 2163ms May 31 06:56:31 unda volumio[7504]: info: ------------------------------ 2162ms May 31 06:56:31 unda volumio[7504]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 31 06:56:31 unda volumio[7504]: info: May 31 06:56:31 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:31 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:31 unda volumio[7504]: info: May 31 06:56:31 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:31 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:34 unda volumio[7504]: info: Getting Spotify volume May 31 06:56:34 unda volumio[7504]: info: Spotify volume: 100 May 31 06:56:34 unda volumio[7504]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 31 06:56:35 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:35 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 31 06:56:35 unda volumio[7504]: info: Adding plugin bluetooth to MyMusic Plugins May 31 06:56:35 unda volumio[7504]: info: Adding plugin multiroom to MyMusic Plugins May 31 06:56:35 unda volumio[7504]: info: Adding plugin metavolumio to MyMusic Plugins May 31 06:56:35 unda volumio[7504]: info: Adding plugin cd_controller to MyMusic Plugins May 31 06:56:35 unda volumio[7504]: info: Adding plugin smart_inputs to MyMusic Plugins May 31 06:56:35 unda volumio[7504]: info: Adding plugin tidalconnect to MyMusic Plugins May 31 06:56:35 unda volumio[7504]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 31 06:56:36 unda volumio[7504]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 31 06:56:36 unda volumio[7504]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 31 06:56:36 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:36 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:36 unda volumio[7504]: info: Starting MyVolumio Remote Streaming Endpoints May 31 06:56:36 unda volumio[7504]: info: MyVolumio login type: Token May 31 06:56:36 unda volumio[7504]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 31 06:56:36 unda volumio[7504]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 31 06:56:36 unda volumio[7504]: info: Starting Streaming Service Transparent Proxy May 31 06:56:36 unda volumio[7504]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 31 06:56:36 unda volumio[7504]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 31 06:56:36 unda volumio[7504]: info: Streaming services startup May 31 06:56:36 unda volumio[7504]: info: Starting Streaming Daemon May 31 06:56:36 unda sudo[7757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 06:56:36 unda sudo[7757]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:36 unda sudo[7757]: pam_unix(sudo:session): session closed for user root May 31 06:56:36 unda volumio[7504]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 31 06:56:37 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:37 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 100 May 31 06:56:37 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 64 May 31 06:56:37 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:37 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 64 May 31 06:56:37 unda volumio[7504]: info: Cannot mount NAS diskstation at system boot, trial number 4 ,retrying in 5 seconds May 31 06:56:37 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 06:56:37 unda volumio[7504]: error: Cannot start Volumio Streaming Daemon May 31 06:56:37 unda volumio[7504]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 06:56:37 unda volumio[7504]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 06:56:37 unda volumio[7504]: STREAMING PROXY: Starting server on port 3245 May 31 06:56:37 unda volumio[7504]: Node JS runtime: 14 May 31 06:56:38 unda volumio[7504]: SPOTIFY: SETTING SPOTIFY VOLUME 64 May 31 06:56:38 unda volumio[7504]: info: Sending Spotify command with payload to local API: /player/volume May 31 06:56:39 unda go-librespot[7670]: time="2025-05-31T06:56:39Z" level=debug msg="update volume to 41942/65535" May 31 06:56:39 unda go-librespot[7670]: time="2025-05-31T06:56:39Z" level=debug msg="put connect state because VOLUME_CHANGED" May 31 06:56:39 unda go-librespot[7670]: time="2025-05-31T06:56:39Z" level=trace msg="emitting websocket event: volume" May 31 06:56:39 unda volumio[7504]: SPOTIFY: received: {"type":"volume","data":{"value":64,"max":100}} May 31 06:56:39 unda volumio[7504]: SPOTIFY: RECEIVED SPOTIFY VOLUME 64 May 31 06:56:40 unda volumio[7504]: info: CoreCommandRouter::Close All Modals sent May 31 06:56:40 unda volumio[7504]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 31 06:56:42 unda volumio[7504]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 31 06:56:43 unda volumio[7504]: info: MyVolumio token set successfully May 31 06:56:43 unda volumio[7504]: info: MYVOLUMIO: Adding device May 31 06:56:43 unda volumio[7504]: info: MYVOLUMIO: Evaluating Server May 31 06:56:44 unda volumio[7504]: Cannot compose Albumart path May 31 06:56:44 unda volumio[7504]: info: May 31 06:56:44 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:44 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:44 unda volumio[7504]: info: sendMpdCommand add "https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein" took 14359 milliseconds May 31 06:56:44 unda volumio[7504]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 06:56:44 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand play May 31 06:56:44 unda volumio[7504]: info: ------------------------------ 13817ms May 31 06:56:44 unda volumio[7504]: info: ------------------------------ 13816ms May 31 06:56:44 unda volumio[7504]: info: ------------------------------ 15ms May 31 06:56:44 unda volumio[7504]: info: sendMpdCommand play took 9 milliseconds May 31 06:56:45 unda volumio[7504]: info: MyVolumio status changed May 31 06:56:45 unda volumio[7504]: info: Streaming services startup May 31 06:56:45 unda volumio[7504]: info: Starting Streaming Daemon May 31 06:56:45 unda volumio[7504]: info: Removing browser output: myVolumio user plan is not superstar May 31 06:56:45 unda volumio[7504]: info: Removing audio output: May 31 06:56:45 unda volumio[7504]: info: Stoppping Tunnel 1 May 31 06:56:45 unda sudo[7824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 06:56:45 unda sudo[7824]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:45 unda sudo[7824]: pam_unix(sudo:session): session closed for user root May 31 06:56:45 unda sudo[7827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 31 06:56:45 unda sudo[7827]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getUIConfig: starting: May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getUIConfig: i18nStrings: May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getUIConfig: i18nStringsDefaults: May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getUIConfig: language code: de dir: /data/plugins/system_hardware/rotaryencoder2 May 31 06:56:45 unda sudo[7827]: pam_unix(sudo:session): session closed for user root May 31 06:56:45 unda volumio[7504]: error: Cannot start Volumio Streaming Daemon May 31 06:56:45 unda volumio[7504]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 06:56:45 unda volumio[7504]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 06:56:45 unda volumio[7504]: info: Remote SSH Stopped May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("VOLUME"):Lautstärke May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("TOGGLEMUTE"):Stumm an/aus May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("PLAYPAUSE"):Pause an/aus May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("DOTS"):... May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("EMIT"):Websocket Nachricht senden May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("EMIT"):Websocket Nachricht senden May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("DOTS"):... May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("EMIT"):Websocket Nachricht senden May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("SKIP"):Titel vor/zurück May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("PLAYPAUSE"):Pause an/aus May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("RANDOM"):Zufällige Wiedergabe May 31 06:56:45 unda volumio[7504]: info: [ROTARYENCODER2] getI18nString("TOGGLEMUTE"):Stumm an/aus May 31 06:56:45 unda volumio[7504]: info: Setting Geolocation for MyVolumio to eu6 May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 16 milliseconds May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 19 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 17 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:45 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus stop May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 33ms May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 14 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand playlistinfo took 12 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand playlistinfo took 12 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 4 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 3 milliseconds May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:45 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:45 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:45 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:45 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 87ms May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 86ms May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand playlistinfo took 54 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand playlistinfo took 52 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand playlistinfo took 53 milliseconds May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:45 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:45 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:45 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:45 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3?aggregator=tunein","artist":null,"album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:45 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:45 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 127ms May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 116ms May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 116ms May 31 06:56:45 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:45 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:45 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:45 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:45 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:45 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:45 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:45 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:45 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:45 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:45 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:45 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:45 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:45 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 8ms May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 6 milliseconds May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 7ms May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 5 milliseconds May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 5ms May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 3 milliseconds May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:45 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:45 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:45 unda volumio[7504]: info: May 31 06:56:45 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 9ms May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 7 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 5ms May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 4 milliseconds May 31 06:56:45 unda volumio[7504]: info: ------------------------------ 3ms May 31 06:56:45 unda volumio[7504]: info: sendMpdCommand status took 2 milliseconds May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:45 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:45 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:45 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:45 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:45 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:45 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:45 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:45 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:46 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:46 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:46 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:46 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:46 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:46 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:46 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:46 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: ------------------------------ 73ms May 31 06:56:46 unda volumio[7504]: info: ------------------------------ 72ms May 31 06:56:46 unda volumio[7504]: info: ------------------------------ 70ms May 31 06:56:46 unda volumio[7504]: info: sendMpdCommand playlistinfo took 60 milliseconds May 31 06:56:46 unda volumio[7504]: info: sendMpdCommand playlistinfo took 60 milliseconds May 31 06:56:46 unda volumio[7504]: info: sendMpdCommand playlistinfo took 59 milliseconds May 31 06:56:46 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:46 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:46 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:46 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:46 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:46 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:46 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:46 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:46 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:46 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:46 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:46 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:46 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:46 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deutschlandfunk - Alles von Relevanz","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:46 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:46 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:46 unda volumio[7504]: info: ------------------------------ 117ms May 31 06:56:46 unda volumio[7504]: info: ------------------------------ 113ms May 31 06:56:46 unda volumio[7504]: info: ------------------------------ 112ms May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64 May 31 06:56:46 unda volumio[7504]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 31 06:56:46 unda volumio[7504]: info: Updating MyVolumio device info May 31 06:56:46 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:47 unda volumio[7504]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 31 06:56:50 unda volumio[7504]: info: MYVOLUMIO: Adding device May 31 06:56:50 unda volumio[7504]: info: MYVOLUMIO: Evaluating Server May 31 06:56:50 unda volumio[7504]: info: Setting Geolocation for MyVolumio to eu4 May 31 06:56:50 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:50 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:50 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:50 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: -1 May 31 06:56:50 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value -1for Rotary: 1 May 31 06:56:50 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME DOWN May 31 06:56:50 unda volumio[7504]: info: VolumeController::SetAlsaVolume- May 31 06:56:50 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:50 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:50 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:50 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 May 31 06:56:50 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 64 May 31 06:56:50 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 62 May 31 06:56:50 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:50 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 62 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: -1 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value -1for Rotary: 1 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME DOWN May 31 06:56:51 unda volumio[7504]: info: VolumeController::SetAlsaVolume- May 31 06:56:51 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:51 unda volumio[7504]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 31 06:56:51 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 May 31 06:56:51 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 62 May 31 06:56:51 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 60 May 31 06:56:51 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:51 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 60 May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 06:56:51 unda volumio[7504]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::volumioGetState May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: -1 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value -1for Rotary: 1 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME DOWN May 31 06:56:51 unda volumio[7504]: info: VolumeController::SetAlsaVolume- May 31 06:56:51 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:51 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 May 31 06:56:51 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 60 May 31 06:56:51 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 58 May 31 06:56:51 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:51 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 58 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: -1 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value -1for Rotary: 1 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME DOWN May 31 06:56:51 unda volumio[7504]: info: VolumeController::SetAlsaVolume- May 31 06:56:51 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:51 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 May 31 06:56:51 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 58 May 31 06:56:51 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 56 May 31 06:56:51 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:51 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 56 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: -1 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value -1for Rotary: 1 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME DOWN May 31 06:56:51 unda volumio[7504]: info: VolumeController::SetAlsaVolume- May 31 06:56:51 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:51 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 31 06:56:51 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 56 May 31 06:56:51 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 54 May 31 06:56:51 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:51 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 54 May 31 06:56:51 unda volumio[7504]: info: Updating MyVolumio device info May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: -1 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value -1for Rotary: 1 May 31 06:56:51 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME DOWN May 31 06:56:51 unda volumio[7504]: info: VolumeController::SetAlsaVolume- May 31 06:56:51 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:51 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:51 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 May 31 06:56:51 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 54 May 31 06:56:51 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 52 May 31 06:56:51 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:51 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 52 May 31 06:56:52 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: -1 May 31 06:56:52 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value -1for Rotary: 1 May 31 06:56:52 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME DOWN May 31 06:56:52 unda volumio[7504]: info: VolumeController::SetAlsaVolume- May 31 06:56:52 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:52 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:52 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:52 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 May 31 06:56:52 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 52 May 31 06:56:52 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 50 May 31 06:56:52 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:52 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 50 May 31 06:56:52 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: -1 May 31 06:56:52 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value -1for Rotary: 1 May 31 06:56:52 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME DOWN May 31 06:56:52 unda volumio[7504]: info: VolumeController::SetAlsaVolume- May 31 06:56:52 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:52 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:52 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:52 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 May 31 06:56:52 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 50 May 31 06:56:52 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 48 May 31 06:56:52 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:52 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 48 May 31 06:56:52 unda volumio[7504]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 31 06:56:52 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: -1 May 31 06:56:52 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value -1for Rotary: 1 May 31 06:56:52 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME DOWN May 31 06:56:52 unda volumio[7504]: info: VolumeController::SetAlsaVolume- May 31 06:56:52 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:52 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:52 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:52 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 May 31 06:56:52 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 48 May 31 06:56:52 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 46 May 31 06:56:52 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:52 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 46 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: 1 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value 1for Rotary: 1 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME UP May 31 06:56:53 unda volumio[7504]: info: VolumeController::SetAlsaVolume+ May 31 06:56:53 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:53 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:53 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:53 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 May 31 06:56:53 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 46 May 31 06:56:53 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 48 May 31 06:56:53 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:53 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 48 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: 1 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value 1for Rotary: 1 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME UP May 31 06:56:53 unda volumio[7504]: info: VolumeController::SetAlsaVolume+ May 31 06:56:53 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:53 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:53 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:53 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 May 31 06:56:53 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 48 May 31 06:56:53 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 50 May 31 06:56:53 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:53 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 50 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: 1 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value 1for Rotary: 1 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME UP May 31 06:56:53 unda volumio[7504]: info: VolumeController::SetAlsaVolume+ May 31 06:56:53 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:53 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:53 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:53 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 May 31 06:56:53 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 50 May 31 06:56:53 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 52 May 31 06:56:53 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:53 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 52 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 1 -> Dir: 1 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 1 with value 1for Rotary: 1 May 31 06:56:53 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: VOLUME UP May 31 06:56:53 unda volumio[7504]: info: VolumeController::SetAlsaVolume+ May 31 06:56:53 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:53 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:53 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:53 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 31 06:56:53 unda volumio[7504]: SPOTIFY: SPOTIFY VOLUME 52 May 31 06:56:53 unda volumio[7504]: SPOTIFY: VOLUMIO VOLUME 54 May 31 06:56:53 unda volumio[7504]: SPOTIFY: DELTA VOLUME ENOUGH: true May 31 06:56:53 unda volumio[7504]: info: Setting Spotify Volume from Volumio: 54 May 31 06:56:54 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 06:56:54 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:54 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 06:56:54 unda volumio-remote-updater[612]: No test mode May 31 06:56:54 unda volumio-remote-updater[612]: No alpha test mode May 31 06:56:54 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 31 06:56:54 unda volumio[7504]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Various Fixes for QOBUZ Connect
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Qobuz Connect Ready
    • \n
\n","title":"Update v3.812","updateavailable":true} May 31 06:56:54 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 06:56:54 unda volumio[7504]: SPOTIFY: SETTING SPOTIFY VOLUME 54 May 31 06:56:54 unda volumio[7504]: info: Sending Spotify command with payload to local API: /player/volume May 31 06:56:54 unda go-librespot[7670]: time="2025-05-31T06:56:54Z" level=debug msg="update volume to 35388/65535" May 31 06:56:55 unda go-librespot[7670]: time="2025-05-31T06:56:55Z" level=debug msg="put connect state because VOLUME_CHANGED" May 31 06:56:55 unda go-librespot[7670]: time="2025-05-31T06:56:55Z" level=trace msg="emitting websocket event: volume" May 31 06:56:55 unda volumio[7504]: SPOTIFY: received: {"type":"volume","data":{"value":54,"max":100}} May 31 06:56:55 unda volumio[7504]: SPOTIFY: RECEIVED SPOTIFY VOLUME 54 May 31 06:56:55 unda volumio[7504]: info: May 31 06:56:55 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:55 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:55 unda volumio[7504]: info: May 31 06:56:55 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:55 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:55 unda volumio[7504]: info: May 31 06:56:55 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:55 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:55 unda volumio[7504]: info: May 31 06:56:55 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:55 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:55 unda volumio[7504]: info: May 31 06:56:55 unda volumio[7504]: ---------------------------- MPD announces system playlist update May 31 06:56:55 unda volumio[7504]: info: Ignoring MPD Status Update May 31 06:56:55 unda volumio[7504]: info: May 31 06:56:55 unda volumio[7504]: ---------------------------- MPD announces state update: player May 31 06:56:55 unda volumio[7504]: info: ControllerMpd::getState May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand status May 31 06:56:55 unda volumio[7504]: info: ------------------------------ 8ms May 31 06:56:55 unda volumio[7504]: info: sendMpdCommand status took 7 milliseconds May 31 06:56:55 unda volumio[7504]: info: ------------------------------ 6ms May 31 06:56:55 unda volumio[7504]: info: sendMpdCommand status took 4 milliseconds May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:55 unda volumio[7504]: info: ------------------------------ 6ms May 31 06:56:55 unda volumio[7504]: info: sendMpdCommand status took 5 milliseconds May 31 06:56:55 unda volumio[7504]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 06:56:55 unda volumio[7504]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::parseState May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:55 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:55 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:55 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:55 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10735,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau, Deutschlandfunk-Nachrichtenredaktion","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:55 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:55 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:55 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 06:56:55 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:55 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:55 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:55 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:55 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:55 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10735,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau, Deutschlandfunk-Nachrichtenredaktion","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:55 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:55 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:55 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:55 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:55 unda volumio[7504]: info: ------------------------------ 51ms May 31 06:56:55 unda volumio[7504]: info: ------------------------------ 49ms May 31 06:56:55 unda volumio[7504]: info: sendMpdCommand playlistinfo took 41 milliseconds May 31 06:56:55 unda volumio[7504]: verbose: ControllerMpd::parseTrackInfo May 31 06:56:55 unda volumio[7504]: info: ControllerMpd::pushState May 31 06:56:55 unda volumio[7504]: info: CoreCommandRouter::servicePushState May 31 06:56:55 unda volumio[7504]: info: CorePlayQueue::getTrack 0 May 31 06:56:55 unda volumio[7504]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10735,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Presseschau, Deutschlandfunk-Nachrichtenredaktion","artist":"Deutschlandfunk","album":null,"uri":"https://st01.sslstream.dlf.de/dlf/01/128/mp3/stream.mp3?aggregator=tunein","trackType":""} May 31 06:56:55 unda volumio[7504]: verbose: CURRENT POSITION 0 May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::syncState stateService play May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::syncState currentStatus play May 31 06:56:55 unda volumio[7504]: info: Received an update from plugin. extracting info from payload May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:55 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:55 unda volumio[7504]: info: CoreStateMachine::pushState May 31 06:56:55 unda volumio[7504]: info: CoreCommandRouter::volumioPushState May 31 06:56:55 unda volumio[7504]: info: ------------------------------ 70ms May 31 06:56:55 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 31 06:56:55 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 31 06:56:55 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 31 06:56:55 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 31 06:56:55 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 31 06:56:55 unda volumio[7504]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::volumioGetBrowseSources May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 06:56:56 unda volumio[7504]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 31 06:56:56 unda volumio[7504]: info: Completed starting MyVolumio Plugin May 31 06:56:56 unda volumio[7504]: info: BOOT COMPLETED May 31 06:56:56 unda volumio[7504]: [Metrics] CommandRouter: 37s 351.65ms May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::volumiosetStartupVolume May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 06:56:56 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 06:56:57 unda volumio[7504]: info: CoreCommandRouter::Close All Modals sent May 31 06:56:57 unda volumio[7504]: info: CoreCommandRouter::Close All Modals sent May 31 06:56:57 unda volumio[7504]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 31 06:56:57 unda volumio[7504]: aplay: main:828: audio open error: Device or resource busy May 31 06:56:58 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 31 06:56:58 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 06:56:58 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 31 06:56:58 unda volumio[7504]: info: [ROTARYENCODER2] addEventHandle received from rotary: 2 -> Dir: -1 May 31 06:56:58 unda volumio[7504]: info: [ROTARYENCODER2] emitDialCommand: 4 with value -1for Rotary: 2 May 31 06:56:58 unda volumio[7504]: info: [ROTARYENCODER2] emit command callMethod with data {"endpoint":"system_hardware/eadog_lcd","method":"up","data":""} May 31 06:56:58 unda volumio[7504]: info: CALLMETHOD: system_hardware eadog_lcd up May 31 06:56:58 unda volumio[7504]: info: CoreCommandRouter::executeOnPlugin: eadog_lcd , up May 31 06:56:58 unda volumio[7504]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 06:56:58 unda volumio[7504]: Error: invalid payload May 31 06:56:58 unda volumio[7504]: at decodeString (/data/plugins/system_hardware/rotaryencoder2/node_modules/socket.io-parser/index.js:355:13) May 31 06:56:58 unda volumio[7504]: at Decoder.add (/data/plugins/system_hardware/rotaryencoder2/node_modules/socket.io-parser/index.js:245:14) May 31 06:56:58 unda volumio[7504]: at Manager.ondata (/data/plugins/system_hardware/rotaryencoder2/node_modules/socket.io-client/lib/manager.js:339:16) May 31 06:56:58 unda volumio[7504]: at Socket. (/data/plugins/system_hardware/rotaryencoder2/node_modules/component-bind/index.js:21:15) May 31 06:56:58 unda volumio[7504]: at Socket.Emitter.emit (/data/plugins/system_hardware/rotaryencoder2/node_modules/component-emitter/index.js:145:20) May 31 06:56:58 unda volumio[7504]: at Socket.onPacket (/data/plugins/system_hardware/rotaryencoder2/node_modules/engine.io-client/lib/socket.js:461:14) May 31 06:56:58 unda volumio[7504]: at WS. (/data/plugins/system_hardware/rotaryencoder2/node_modules/engine.io-client/lib/socket.js:278:10) May 31 06:56:58 unda volumio[7504]: at WS.Emitter.emit (/data/plugins/system_hardware/rotaryencoder2/node_modules/component-emitter/index.js:145:20) May 31 06:56:58 unda volumio[7504]: at WS.Transport.onPacket (/data/plugins/system_hardware/rotaryencoder2/node_modules/engine.io-client/lib/transport.js:149:8) May 31 06:56:58 unda volumio[7504]: at WS.Transport.onData (/data/plugins/system_hardware/rotaryencoder2/node_modules/engine.io-client/lib/transport.js:141:8) May 31 06:56:58 unda volumio[7504]: at WebSocket.ws.onmessage (/data/plugins/system_hardware/rotaryencoder2/node_modules/engine.io-client/lib/transports/websocket.js:160:10) May 31 06:56:58 unda volumio[7504]: at WebSocket.onMessage (/data/plugins/system_hardware/rotaryencoder2/node_modules/ws/lib/event-target.js:132:16) May 31 06:56:58 unda volumio[7504]: at WebSocket.emit (events.js:315:20) May 31 06:56:58 unda volumio[7504]: at Receiver.receiverOnMessage (/data/plugins/system_hardware/rotaryencoder2/node_modules/ws/lib/websocket.js:1070:20) May 31 06:56:58 unda volumio[7504]: at Receiver.emit (events.js:315:20) May 31 06:56:58 unda volumio[7504]: at Receiver.dataMessage (/data/plugins/system_hardware/rotaryencoder2/node_modules/ws/lib/receiver.js:517:14) May 31 06:56:58 unda volumio[7504]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 06:56:58 unda sudo[7873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 06:55 May 31 06:56:58 unda sudo[7873]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"