Dec 22 14:17:00 volumio volumio[1260]: info: Dec 22 14:17:00 volumio volumio[1260]: ---------------------------- MPD announces state update: player Dec 22 14:17:00 volumio volumio[1260]: info: ControllerMpd::getState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:17:00 volumio volumio[1260]: info: Dec 22 14:17:00 volumio volumio[1260]: ---------------------------- MPD announces state update: player Dec 22 14:17:00 volumio volumio[1260]: info: ControllerMpd::getState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:17:00 volumio volumio[1260]: info: Dec 22 14:17:00 volumio volumio[1260]: ---------------------------- MPD announces state update: player Dec 22 14:17:00 volumio volumio[1260]: info: Dec 22 14:17:00 volumio volumio[1260]: ---------------------------- MPD announces system playlist update Dec 22 14:17:00 volumio volumio[1260]: info: Ignoring MPD Status Update Dec 22 14:17:00 volumio volumio[1260]: info: Dec 22 14:17:00 volumio volumio[1260]: ---------------------------- MPD announces state update: player Dec 22 14:17:00 volumio volumio[1260]: info: ControllerMpd::getState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:17:00 volumio volumio[1260]: info: sendMpdCommand status took 83 milliseconds Dec 22 14:17:00 volumio volumio[1260]: info: ControllerMpd::getState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:17:00 volumio volumio[1260]: info: sendMpdCommand status took 63 milliseconds Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::parseState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::parseState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:17:00 volumio volumio[1260]: info: Dec 22 14:17:00 volumio volumio[1260]: ---------------------------- MPD announces state update: player Dec 22 14:17:00 volumio volumio[1260]: info: ControllerMpd::getState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:17:00 volumio volumio[1260]: info: Dec 22 14:17:00 volumio volumio[1260]: ---------------------------- MPD announces system playlist update Dec 22 14:17:00 volumio volumio[1260]: info: Ignoring MPD Status Update Dec 22 14:17:00 volumio volumio[1260]: info: Dec 22 14:17:00 volumio volumio[1260]: ---------------------------- MPD announces state update: player Dec 22 14:17:00 volumio volumio[1260]: info: ControllerMpd::getState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:17:00 volumio volumio[1260]: info: sendMpdCommand status took 104 milliseconds Dec 22 14:17:00 volumio volumio[1260]: info: ------------------------------ 128ms Dec 22 14:17:00 volumio volumio[1260]: info: sendMpdCommand status took 87 milliseconds Dec 22 14:17:00 volumio volumio[1260]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 22 14:17:00 volumio volumio[1260]: info: sendMpdCommand playlistinfo took 30 milliseconds Dec 22 14:17:00 volumio volumio[1260]: info: sendMpdCommand status took 14 milliseconds Dec 22 14:17:00 volumio volumio[1260]: info: ------------------------------ 9ms Dec 22 14:17:00 volumio volumio[1260]: info: sendMpdCommand status took 4 milliseconds Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::parseState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::parseState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::parseState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::parseState Dec 22 14:17:00 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:17:00 volumio volumio[1260]: info: ControllerMpd::pushState Dec 22 14:17:00 volumio volumio[1260]: info: CoreCommandRouter::servicePushState Dec 22 14:17:00 volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Dec 22 14:17:00 volumio volumio[1260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - CLUBHITS","artist":"90s90s - CLUBHITS","album":null,"uri":"http://streams.90s90s.de/main/mp3-192/tunein/","trackType":""} Dec 22 14:17:00 volumio volumio[1260]: verbose: CURRENT POSITION 0 Dec 22 14:17:00 volumio volumio[1260]: info: CoreStateMachine::syncState stateService play Dec 22 14:17:00 volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus stop Dec 22 14:17:00 volumio volumio[1260]: info: ControllerMpd::pushState Dec 22 14:17:00 volumio volumio[1260]: info: CoreCommandRouter::servicePushState Dec 22 14:17:00 volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Dec 22 14:17:00 volumio volumio[1260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - CLUBHITS","artist":"90s90s - CLUBHITS","album":null,"uri":"http://streams.90s90s.de/main/mp3-192/tunein/","trackType":""} Dec 22 14:17:00 volumio volumio[1260]: verbose: CURRENT POSITION 0 Dec 22 14:17:00 volumio volumio[1260]: info: CoreStateMachine::syncState stateService play Dec 22 14:17:00 volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:17:00 volumio volumio[1260]: info: Received an update from plugin. extracting info from payload Dec 22 14:17:00 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:00 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:17:00 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:01 volumio volumio[1260]: info: ------------------------------ 484ms Dec 22 14:17:01 volumio volumio[1260]: info: ------------------------------ 604ms Dec 22 14:17:01 volumio volumio[1260]: info: Dec 22 14:17:01 volumio volumio[1260]: ---------------------------- MPD announces system playlist update Dec 22 14:17:01 volumio volumio[1260]: info: Ignoring MPD Status Update Dec 22 14:17:01 volumio volumio[1260]: info: Dec 22 14:17:01 volumio volumio[1260]: ---------------------------- MPD announces state update: player Dec 22 14:17:01 volumio volumio[1260]: info: ControllerMpd::getState Dec 22 14:17:01 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:17:01 volumio volumio[1260]: info: sendMpdCommand playlistinfo took 498 milliseconds Dec 22 14:17:01 volumio volumio[1260]: info: sendMpdCommand playlistinfo took 499 milliseconds Dec 22 14:17:01 volumio volumio[1260]: info: sendMpdCommand playlistinfo took 483 milliseconds Dec 22 14:17:01 volumio volumio[1260]: info: sendMpdCommand playlistinfo took 476 milliseconds Dec 22 14:17:01 volumio volumio[1260]: info: ------------------------------ 46ms Dec 22 14:17:01 volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:17:01 volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:17:01 volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:17:01 volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:17:01 volumio volumio[1260]: info: ControllerMpd::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::servicePushState Dec 22 14:17:01 volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Dec 22 14:17:01 volumio volumio[1260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - CLUBHITS","artist":"90s90s - CLUBHITS","album":null,"uri":"http://streams.90s90s.de/main/mp3-192/tunein/","trackType":""} Dec 22 14:17:01 volumio volumio[1260]: verbose: CURRENT POSITION 0 Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::syncState stateService play Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:17:01 volumio volumio[1260]: info: Received an update from plugin. extracting info from payload Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:01 volumio volumio[1260]: info: ControllerMpd::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::servicePushState Dec 22 14:17:01 volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Dec 22 14:17:01 volumio volumio[1260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - CLUBHITS","artist":"90s90s - CLUBHITS","album":null,"uri":"http://streams.90s90s.de/main/mp3-192/tunein/","trackType":""} Dec 22 14:17:01 volumio volumio[1260]: verbose: CURRENT POSITION 0 Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::syncState stateService play Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:17:01 volumio volumio[1260]: info: Received an update from plugin. extracting info from payload Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:01 volumio volumio[1260]: info: ControllerMpd::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::servicePushState Dec 22 14:17:01 volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Dec 22 14:17:01 volumio volumio[1260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - CLUBHITS","artist":"90s90s - CLUBHITS","album":null,"uri":"http://streams.90s90s.de/main/mp3-192/tunein/","trackType":""} Dec 22 14:17:01 volumio volumio[1260]: verbose: CURRENT POSITION 0 Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::syncState stateService play Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:17:01 volumio volumio[1260]: info: Received an update from plugin. extracting info from payload Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:01 volumio volumio[1260]: info: ControllerMpd::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::servicePushState Dec 22 14:17:01 volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Dec 22 14:17:01 volumio volumio[1260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - CLUBHITS","artist":"90s90s - CLUBHITS","album":null,"uri":"http://streams.90s90s.de/main/mp3-192/tunein/","trackType":""} Dec 22 14:17:01 volumio volumio[1260]: verbose: CURRENT POSITION 0 Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::syncState stateService play Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:17:01 volumio volumio[1260]: info: Received an update from plugin. extracting info from payload Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:01 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:02 volumio volumio[1260]: info: ------------------------------ 1673ms Dec 22 14:17:02 volumio volumio[1260]: info: ------------------------------ 1672ms Dec 22 14:17:02 volumio volumio[1260]: info: ------------------------------ 1563ms Dec 22 14:17:02 volumio volumio[1260]: info: ------------------------------ 1586ms Dec 22 14:17:02 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:02 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:02 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:02 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:02 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:02 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:02 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:02 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:02 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:02 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:02 volumio volumio[1260]: info: sendMpdCommand status took 1674 milliseconds Dec 22 14:17:02 volumio volumio[1260]: verbose: ControllerMpd::parseState Dec 22 14:17:02 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:17:03 volumio volumio[1260]: info: sendMpdCommand playlistinfo took 50 milliseconds Dec 22 14:17:03 volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:17:03 volumio volumio[1260]: info: ControllerMpd::pushState Dec 22 14:17:03 volumio volumio[1260]: info: CoreCommandRouter::servicePushState Dec 22 14:17:03 volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Dec 22 14:17:03 volumio volumio[1260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1477,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"90s90s - CLUBHITS","artist":"90s90s - CLUBHITS","album":null,"uri":"http://streams.90s90s.de/main/mp3-192/tunein/","trackType":""} Dec 22 14:17:03 volumio volumio[1260]: verbose: CURRENT POSITION 0 Dec 22 14:17:03 volumio volumio[1260]: info: CoreStateMachine::syncState stateService play Dec 22 14:17:03 volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:17:03 volumio volumio[1260]: info: Received an update from plugin. extracting info from payload Dec 22 14:17:03 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:03 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:17:03 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:03 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:03 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:03 volumio volumio[1260]: info: ------------------------------ 2053ms Dec 22 14:17:03 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:03 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:05 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 14:17:05 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 14:17:09 volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:17:09 volumio volumio[1260]: info: AutoStart - getUIConfig Dec 22 14:17:14 volumio volumio[1260]: info: CALLMETHOD: system_controller autostart saveOptions [object Object] Dec 22 14:17:14 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: autostart , saveOptions Dec 22 14:17:14 volumio volumio[1260]: info: AutoStart - saving settings Dec 22 14:17:14 volumio volumio[1260]: info: AutoStart - settings saved Dec 22 14:17:17 volumio volumio[1260]: info: Dec 22 14:17:17 volumio volumio[1260]: ---------------------------- MPD announces system playlist update Dec 22 14:17:17 volumio volumio[1260]: info: Ignoring MPD Status Update Dec 22 14:17:17 volumio volumio[1260]: info: Dec 22 14:17:17 volumio volumio[1260]: ---------------------------- MPD announces state update: player Dec 22 14:17:17 volumio volumio[1260]: info: ControllerMpd::getState Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:17:17 volumio volumio[1260]: info: Dec 22 14:17:17 volumio volumio[1260]: ---------------------------- MPD announces system playlist update Dec 22 14:17:17 volumio volumio[1260]: info: Ignoring MPD Status Update Dec 22 14:17:17 volumio volumio[1260]: info: Dec 22 14:17:17 volumio volumio[1260]: ---------------------------- MPD announces state update: player Dec 22 14:17:17 volumio volumio[1260]: info: ControllerMpd::getState Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:17:17 volumio volumio[1260]: info: Dec 22 14:17:17 volumio volumio[1260]: ---------------------------- MPD announces system playlist update Dec 22 14:17:17 volumio volumio[1260]: info: Ignoring MPD Status Update Dec 22 14:17:17 volumio volumio[1260]: info: Dec 22 14:17:17 volumio volumio[1260]: ---------------------------- MPD announces state update: player Dec 22 14:17:17 volumio volumio[1260]: info: ControllerMpd::getState Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand status Dec 22 14:17:17 volumio volumio[1260]: info: ------------------------------ 48ms Dec 22 14:17:17 volumio volumio[1260]: info: sendMpdCommand status took 37 milliseconds Dec 22 14:17:17 volumio volumio[1260]: info: ------------------------------ 60ms Dec 22 14:17:17 volumio volumio[1260]: info: sendMpdCommand status took 39 milliseconds Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::parseState Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::parseState Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:17:17 volumio volumio[1260]: info: ------------------------------ 33ms Dec 22 14:17:17 volumio volumio[1260]: info: sendMpdCommand status took 32 milliseconds Dec 22 14:17:17 volumio volumio[1260]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 22 14:17:17 volumio volumio[1260]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::parseState Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:17:17 volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:17:17 volumio volumio[1260]: info: ControllerMpd::pushState Dec 22 14:17:17 volumio volumio[1260]: info: CoreCommandRouter::servicePushState Dec 22 14:17:17 volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Dec 22 14:17:17 volumio volumio[1260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Stereo MC's - Step It Up","artist":"90s90s - CLUBHITS","album":null,"uri":"http://streams.90s90s.de/main/mp3-192/tunein/","trackType":""} Dec 22 14:17:17 volumio volumio[1260]: verbose: CURRENT POSITION 0 Dec 22 14:17:17 volumio volumio[1260]: info: CoreStateMachine::syncState stateService play Dec 22 14:17:17 volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:17:17 volumio volumio[1260]: info: Received an update from plugin. extracting info from payload Dec 22 14:17:17 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:17 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:17:17 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:17 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:17 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:17 volumio volumio[1260]: info: ControllerMpd::pushState Dec 22 14:17:17 volumio volumio[1260]: info: CoreCommandRouter::servicePushState Dec 22 14:17:17 volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Dec 22 14:17:17 volumio volumio[1260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Stereo MC's - Step It Up","artist":"90s90s - CLUBHITS","album":null,"uri":"http://streams.90s90s.de/main/mp3-192/tunein/","trackType":""} Dec 22 14:17:17 volumio volumio[1260]: verbose: CURRENT POSITION 0 Dec 22 14:17:17 volumio volumio[1260]: info: CoreStateMachine::syncState stateService play Dec 22 14:17:18 volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:17:18 volumio volumio[1260]: info: Received an update from plugin. extracting info from payload Dec 22 14:17:18 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:18 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:18 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:18 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:18 volumio volumio[1260]: info: ------------------------------ 633ms Dec 22 14:17:18 volumio volumio[1260]: info: ------------------------------ 602ms Dec 22 14:17:18 volumio volumio[1260]: info: sendMpdCommand playlistinfo took 563 milliseconds Dec 22 14:17:18 volumio volumio[1260]: verbose: ControllerMpd::parseTrackInfo Dec 22 14:17:18 volumio volumio[1260]: info: ControllerMpd::pushState Dec 22 14:17:18 volumio volumio[1260]: info: CoreCommandRouter::servicePushState Dec 22 14:17:18 volumio volumio[1260]: info: CorePlayQueue::getTrack 0 Dec 22 14:17:18 volumio volumio[1260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":18081,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Stereo MC's - Step It Up","artist":"90s90s - CLUBHITS","album":null,"uri":"http://streams.90s90s.de/main/mp3-192/tunein/","trackType":""} Dec 22 14:17:18 volumio volumio[1260]: verbose: CURRENT POSITION 0 Dec 22 14:17:18 volumio volumio[1260]: info: CoreStateMachine::syncState stateService play Dec 22 14:17:18 volumio volumio[1260]: info: CoreStateMachine::syncState currentStatus play Dec 22 14:17:18 volumio volumio[1260]: info: Received an update from plugin. extracting info from payload Dec 22 14:17:18 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:18 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 14:17:18 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:18 volumio volumio[1260]: info: CoreStateMachine::pushState Dec 22 14:17:18 volumio volumio[1260]: info: CoreCommandRouter::volumioPushState Dec 22 14:17:18 volumio volumio[1260]: info: ------------------------------ 661ms Dec 22 14:17:18 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:18 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:18 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:18 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:18 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:18 volumio volumio[1260]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Dec 22 14:17:18 volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Dec 22 14:17:19 volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:17:19 volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:17:19 volumio volumio[1260]: info: Retrieving Cloud Streaming UI Dec 22 14:17:19 volumio volumio[1260]: info: Getting Tidal Cloud Configuration Dec 22 14:17:19 volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:17:19 volumio volumio[1260]: info: Getting Qobuz Cloud Configuration Dec 22 14:17:19 volumio volumio[1260]: info: Asking plugin for UI Config Dec 22 14:17:19 volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:17:19 volumio volumio[1260]: info: Getting Spotify Cloud Configuration Dec 22 14:17:19 volumio volumio[1260]: info: Asking plugin for UI Config Dec 22 14:17:19 volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:17:19 volumio volumio[1260]: info: Saving Spotify Acccount Dec 22 14:17:19 volumio volumio[1260]: info: Got Tidal Cloud Configuration Dec 22 14:17:19 volumio volumio[1260]: info: Got it Dec 22 14:17:19 volumio volumio[1260]: info: Got it Dec 22 14:17:19 volumio volumio[1260]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 22 14:17:19 volumio volumio[1260]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 14:17:19 volumio volumio[1260]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:17:20 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 22 14:17:21 volumio go-librespot[1670]: time="2025-12-22T14:17:21+01:00" level=trace msg="received accesspoint ping" Dec 22 14:17:21 volumio go-librespot[1670]: time="2025-12-22T14:17:21+01:00" level=trace msg="received accesspoint pong ack" Dec 22 14:17:21 volumio go-librespot[1670]: time="2025-12-22T14:17:21+01:00" level=trace msg="sent dealer ping" Dec 22 14:17:21 volumio go-librespot[1670]: time="2025-12-22T14:17:21+01:00" level=trace msg="received dealer pong" Dec 22 14:17:24 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:17:28 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:17:31 volumio volumio[1260]: info: Enabling MyMusic plugin bluetooth Dec 22 14:17:31 volumio volumio[1260]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Dec 22 14:17:31 volumio volumio[1260]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Dec 22 14:17:31 volumio volumio[1260]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Dec 22 14:17:31 volumio volumio[1260]: error: Could not Enable MyMusic Plugin: Error Dec 22 14:17:32 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:17:36 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:17:40 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:17:44 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 22 14:17:48 volumio volumio[1260]: info: CoreCommandRouter::volumioGetState Dec 22 14:17:51 volumio go-librespot[1670]: time="2025-12-22T14:17:51+01:00" level=trace msg="sent dealer ping" Dec 22 14:17:51 volumio go-librespot[1670]: time="2025-12-22T14:17:51+01:00" level=trace msg="received dealer pong" Dec 22 14:17:52 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 14:17:52 volumio volumio[1260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 14:17:58 volumio volumio[1260]: info: Starting Uninstall of plugin user_interface - touch_display Dec 22 14:17:58 volumio volumio[1260]: info: Uninstalling plugin touch_display Dec 22 14:17:58 volumio sudo[2198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 22 14:17:58 volumio sudo[2198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:17:58 volumio sudo[2198]: pam_unix(sudo:session): session closed for user root Dec 22 14:17:58 volumio sudo[2201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 22 14:17:58 volumio sudo[2201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:17:58 volumio sudo[2203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 22 14:17:58 volumio sudo[2203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:17:58 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 22 14:17:58 volumio volumio[1260]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 22 14:17:58 volumio volumio[1260]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 14:17:58 volumio sudo[2206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 22 14:17:58 volumio sudo[2206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 14:17:58 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 22 14:17:58 volumio volumio[1260]: TypeError: Cannot read properties of undefined (reading 'split') Dec 22 14:17:58 volumio volumio[1260]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 22 14:17:58 volumio volumio[1260]: at ChildProcess.exithandler (node:child_process:413:7) Dec 22 14:17:58 volumio volumio[1260]: at ChildProcess.emit (node:events:514:28) Dec 22 14:17:58 volumio volumio[1260]: at maybeClose (node:internal/child_process:1105:16) Dec 22 14:17:58 volumio volumio[1260]: at Socket. (node:internal/child_process:457:11) Dec 22 14:17:58 volumio volumio[1260]: at Socket.emit (node:events:514:28) Dec 22 14:17:58 volumio volumio[1260]: at Pipe. (node:net:337:12) Dec 22 14:17:58 volumio systemd[1]: Reloading. Dec 22 14:17:58 volumio volumio[1260]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 14:17:59 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 14:17:59 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 14:18:00 volumio sudo[2206]: pam_unix(sudo:session): session closed for user root Dec 22 14:18:00 volumio sudo[2201]: pam_unix(sudo:session): session closed for user root Dec 22 14:18:00 volumio sudo[2203]: pam_unix(sudo:session): session closed for user root Dec 22 14:18:01 volumio sudo[2241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 14:17' Dec 22 14:18:01 volumio sudo[2241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"