-- Logs begin at Sun 2025-09-14 09:39:48 CEST, end at Sun 2025-09-14 09:52:00 CEST. -- Sep 14 09:51:00 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:00 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:01 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:01 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:01 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:01 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:02 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:02 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:02 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:02 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:03 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:03 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:03 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:04 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:04 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:04 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:04 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:04 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:04 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:04 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:04 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:04 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:04 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:04 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:04 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:04 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:04 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:04 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:04 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:04 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:04 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:05 volumio volumio[798]: info: CoreCommandRouter::volumioGetState Sep 14 09:51:05 volumio volumio[798]: info: Sep 14 09:51:05 volumio volumio[798]: ---------------------------- MPD announces system playlist update Sep 14 09:51:05 volumio volumio[798]: info: Ignoring MPD Status Update Sep 14 09:51:05 volumio volumio[798]: info: Sep 14 09:51:05 volumio volumio[798]: ---------------------------- MPD announces state update: player Sep 14 09:51:05 volumio volumio[798]: info: ControllerMpd::getState Sep 14 09:51:05 volumio volumio[798]: verbose: ControllerMpd::sendMpdCommand status Sep 14 09:51:05 volumio volumio[798]: info: Sep 14 09:51:05 volumio volumio[798]: ---------------------------- MPD announces system playlist update Sep 14 09:51:05 volumio volumio[798]: info: Ignoring MPD Status Update Sep 14 09:51:05 volumio volumio[798]: info: Sep 14 09:51:05 volumio volumio[798]: ---------------------------- MPD announces state update: player Sep 14 09:51:05 volumio volumio[798]: info: ControllerMpd::getState Sep 14 09:51:05 volumio volumio[798]: verbose: ControllerMpd::sendMpdCommand status Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 78 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 88 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 14 09:51:05 volumio volumio[798]: info: Setting Spotify Volume from Volumio: 88 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 88 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 14 09:51:05 volumio volumio[798]: info: Setting Spotify Volume from Volumio: 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:05 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:06 volumio go-librespot[1161]: time="2025-09-14T09:51:06+02:00" level=debug msg="update volume requested to 51117/65535" Sep 14 09:51:06 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:06 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:06 volumio go-librespot[1161]: time="2025-09-14T09:51:06+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Sep 14 09:51:06 volumio go-librespot[1161]: time="2025-09-14T09:51:06+02:00" level=trace msg="emitting websocket event: volume" Sep 14 09:51:06 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:06 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:07 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:07 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:08 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:08 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:08 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:09 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:09 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:09 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:09 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:10 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:10 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:10 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:10 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:11 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:11 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:11 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:11 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:11 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:11 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:11 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:11 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:11 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:11 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:11 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:11 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:11 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:11 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:12 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:12 volumio volumio[798]: info: ------------------------------ 7592ms Sep 14 09:51:12 volumio volumio[798]: info: sendMpdCommand status took 7585 milliseconds Sep 14 09:51:12 volumio volumio[798]: info: ------------------------------ 7586ms Sep 14 09:51:12 volumio volumio[798]: info: sendMpdCommand status took 7559 milliseconds Sep 14 09:51:12 volumio volumio[798]: verbose: ControllerMpd::parseState Sep 14 09:51:12 volumio volumio[798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 09:51:12 volumio volumio[798]: verbose: ControllerMpd::parseState Sep 14 09:51:12 volumio volumio[798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 09:51:13 volumio volumio[798]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Sep 14 09:51:13 volumio volumio[798]: info: Sending Spotify command with payload to local API: /player/volume Sep 14 09:51:13 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:13 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:13 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:13 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:13 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:14 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:14 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:14 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:14 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:15 volumio volumio[798]: info: sendMpdCommand playlistinfo took 3155 milliseconds Sep 14 09:51:15 volumio volumio[798]: info: sendMpdCommand playlistinfo took 3148 milliseconds Sep 14 09:51:15 volumio volumio[798]: verbose: ControllerMpd::parseTrackInfo Sep 14 09:51:15 volumio volumio[798]: verbose: ControllerMpd::parseTrackInfo Sep 14 09:51:15 volumio volumio[798]: info: ControllerMpd::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::servicePushState Sep 14 09:51:15 volumio volumio[798]: info: CorePlayQueue::getTrack 0 Sep 14 09:51:15 volumio volumio[798]: verbose: STATE SERVICE {"status":"play","position":0,"seek":337077,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calvin Harris & Jessie Reyez - Ocean","artist":"RMF MAXXX","album":null,"uri":"http://195.150.20.7/rmf_maxxx","trackType":""} Sep 14 09:51:15 volumio volumio[798]: verbose: CURRENT POSITION 0 Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::syncState stateService play Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::syncState currentStatus play Sep 14 09:51:15 volumio volumio[798]: info: Received an update from plugin. extracting info from payload Sep 14 09:51:15 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:15 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:16 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:16 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:16 volumio volumio[798]: info: ControllerMpd::pushState Sep 14 09:51:16 volumio volumio[798]: info: CoreCommandRouter::servicePushState Sep 14 09:51:16 volumio volumio[798]: info: CorePlayQueue::getTrack 0 Sep 14 09:51:16 volumio volumio[798]: verbose: STATE SERVICE {"status":"play","position":0,"seek":337180,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calvin Harris & Jessie Reyez - Ocean","artist":"RMF MAXXX","album":null,"uri":"http://195.150.20.7/rmf_maxxx","trackType":""} Sep 14 09:51:16 volumio volumio[798]: verbose: CURRENT POSITION 0 Sep 14 09:51:16 volumio volumio[798]: info: CoreStateMachine::syncState stateService play Sep 14 09:51:16 volumio volumio[798]: info: CoreStateMachine::syncState currentStatus play Sep 14 09:51:16 volumio volumio[798]: info: Received an update from plugin. extracting info from payload Sep 14 09:51:16 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:16 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:16 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:16 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:16 volumio volumio[798]: info: ------------------------------ 10961ms Sep 14 09:51:16 volumio volumio[798]: info: ------------------------------ 10944ms Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: received: {"type":"volume","data":{"value":78,"max":100}} Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED SPOTIFY VOLUME 78 Sep 14 09:51:16 volumio volumio[798]: info: Setting Volumio Volume from Spotify: 78 Sep 14 09:51:16 volumio volumio[798]: info: VolumeController::SetAlsaVolume78 Sep 14 09:51:16 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:16 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:16 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 78 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 14 09:51:16 volumio volumio[798]: info: Setting Spotify Volume from Volumio: 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 99 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: false Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 78 Sep 14 09:51:16 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 14 09:51:16 volumio volumio[798]: info: Setting Spotify Volume from Volumio: 78 Sep 14 09:51:17 volumio go-librespot[1161]: time="2025-09-14T09:51:17+02:00" level=debug msg="update volume requested to 65535/65535" Sep 14 09:51:17 volumio go-librespot[1161]: time="2025-09-14T09:51:17+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Sep 14 09:51:17 volumio go-librespot[1161]: time="2025-09-14T09:51:17+02:00" level=trace msg="emitting websocket event: volume" Sep 14 09:51:17 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:17 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:17 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:17 volumio go-librespot[1161]: time="2025-09-14T09:51:17+02:00" level=trace msg="received accesspoint ping" Sep 14 09:51:17 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:17 volumio go-librespot[1161]: time="2025-09-14T09:51:17+02:00" level=trace msg="received accesspoint pong ack" Sep 14 09:51:17 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:18 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:18 volumio go-librespot[1161]: time="2025-09-14T09:51:18+02:00" level=trace msg="sent dealer ping" Sep 14 09:51:18 volumio go-librespot[1161]: time="2025-09-14T09:51:18+02:00" level=trace msg="received dealer pong" Sep 14 09:51:18 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:18 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:18 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:19 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:19 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:19 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:19 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:20 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:20 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:20 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:20 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:21 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:21 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:21 volumio volumio-remote-updater[350]: [2025-09-14 09:51:21] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Sep 14 09:51:21 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:22 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:22 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:22 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:23 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:23 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:23 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:23 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:23 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:24 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:24 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:25 volumio volumio[798]: info: CoreCommandRouter::volumioGetState Sep 14 09:51:25 volumio volumio[798]: info: CoreCommandRouter::volumioGetState Sep 14 09:51:26 volumio volumio[798]: SPOTIFY: SETTING SPOTIFY VOLUME 78 Sep 14 09:51:26 volumio volumio[798]: info: Sending Spotify command with payload to local API: /player/volume Sep 14 09:51:26 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:26 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:26 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:26 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:26 volumio volumio-remote-updater[350]: [2025-09-14 09:51:26] [connect] Successful connection Sep 14 09:51:26 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:27 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:27 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:27 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:27 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:28 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:28 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:28 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:28 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:29 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:29 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:29 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:29 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:30 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:30 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:30 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:30 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:31 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:31 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:31 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:32 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:32 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:32 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:32 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:32 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:32 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:32 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:32 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Sep 14 09:51:32 volumio volumio[798]: info: Setting Volumio Volume from Spotify: 100 Sep 14 09:51:32 volumio volumio[798]: info: VolumeController::SetAlsaVolume100 Sep 14 09:51:32 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:32 volumio volumio[798]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] Sep 14 09:51:32 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: SPOTIFY VOLUME 100 Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: VOLUMIO VOLUME 55 Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 14 09:51:32 volumio volumio[798]: info: Setting Spotify Volume from Volumio: 55 Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:32 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:33 volumio sudo[10982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Sep 14 09:51:33 volumio sudo[10982]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 09:51:33 volumio sudo[10982]: pam_unix(sudo:session): session closed for user root Sep 14 09:51:33 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:33 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:33 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:33 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:33 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:33 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:33 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:33 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:33 volumio volumio[798]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 14 09:51:33 volumio go-librespot[1161]: time="2025-09-14T09:51:33+02:00" level=debug msg="update volume requested to 51117/65535" Sep 14 09:51:33 volumio volumio[798]: info: Listing playlists Sep 14 09:51:34 volumio go-librespot[1161]: time="2025-09-14T09:51:34+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Sep 14 09:51:34 volumio go-librespot[1161]: time="2025-09-14T09:51:34+02:00" level=trace msg="emitting websocket event: volume" Sep 14 09:51:34 volumio volumio[798]: info: Sep 14 09:51:34 volumio volumio[798]: ---------------------------- MPD announces system playlist update Sep 14 09:51:34 volumio volumio[798]: info: Ignoring MPD Status Update Sep 14 09:51:34 volumio volumio[798]: info: Sep 14 09:51:34 volumio volumio[798]: ---------------------------- MPD announces state update: player Sep 14 09:51:34 volumio volumio[798]: info: ControllerMpd::getState Sep 14 09:51:34 volumio volumio[798]: verbose: ControllerMpd::sendMpdCommand status Sep 14 09:51:34 volumio volumio[798]: info: Sep 14 09:51:34 volumio volumio[798]: ---------------------------- MPD announces system playlist update Sep 14 09:51:34 volumio volumio[798]: info: Ignoring MPD Status Update Sep 14 09:51:34 volumio volumio[798]: info: Sep 14 09:51:34 volumio volumio[798]: ---------------------------- MPD announces state update: player Sep 14 09:51:34 volumio volumio[798]: info: ControllerMpd::getState Sep 14 09:51:34 volumio volumio[798]: verbose: ControllerMpd::sendMpdCommand status Sep 14 09:51:34 volumio volumio[798]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 14 09:51:34 volumio volumio[798]: SPOTIFY: SETTING SPOTIFY VOLUME 55 Sep 14 09:51:34 volumio volumio[798]: info: Sending Spotify command with payload to local API: /player/volume Sep 14 09:51:34 volumio volumio[798]: info: CoreCommandRouter::volumioGetState Sep 14 09:51:34 volumio volumio[798]: info: ------------------------------ 547ms Sep 14 09:51:34 volumio volumio[798]: info: sendMpdCommand status took 530 milliseconds Sep 14 09:51:34 volumio volumio[798]: info: ------------------------------ 521ms Sep 14 09:51:34 volumio volumio[798]: info: sendMpdCommand status took 513 milliseconds Sep 14 09:51:34 volumio volumio[798]: verbose: ControllerMpd::parseState Sep 14 09:51:34 volumio volumio[798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 09:51:34 volumio volumio[798]: verbose: ControllerMpd::parseState Sep 14 09:51:34 volumio volumio[798]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 09:51:34 volumio sudo[10999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 1 Sep 14 09:51:34 volumio sudo[10999]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 09:51:34 volumio volumio[798]: SPOTIFY: received: {"type":"volume","data":{"value":78,"max":100}} Sep 14 09:51:34 volumio volumio[798]: SPOTIFY: RECEIVED SPOTIFY VOLUME 78 Sep 14 09:51:34 volumio volumio[798]: info: Setting Volumio Volume from Spotify: 78 Sep 14 09:51:34 volumio volumio[798]: info: VolumeController::SetAlsaVolume78 Sep 14 09:51:35 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:35 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:35 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:35 volumio sudo[10999]: pam_unix(sudo:session): session closed for user root Sep 14 09:51:35 volumio go-librespot[1161]: time="2025-09-14T09:51:35+02:00" level=debug msg="update volume requested to 36044/65535" Sep 14 09:51:35 volumio volumio[798]: info: sendMpdCommand playlistinfo took 802 milliseconds Sep 14 09:51:35 volumio volumio[798]: info: sendMpdCommand playlistinfo took 806 milliseconds Sep 14 09:51:35 volumio volumio[798]: verbose: ControllerMpd::parseTrackInfo Sep 14 09:51:35 volumio volumio[798]: info: ControllerMpd::pushState Sep 14 09:51:35 volumio volumio[798]: info: CoreCommandRouter::servicePushState Sep 14 09:51:35 volumio volumio[798]: info: CorePlayQueue::getTrack 0 Sep 14 09:51:35 volumio volumio[798]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366081,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calvin Harris & Jessie Reyez - Ocean","artist":"RMF MAXXX","album":null,"uri":"http://195.150.20.7/rmf_maxxx","trackType":""} Sep 14 09:51:35 volumio volumio[798]: verbose: CURRENT POSITION 0 Sep 14 09:51:35 volumio volumio[798]: info: CoreStateMachine::syncState stateService play Sep 14 09:51:35 volumio volumio[798]: info: CoreStateMachine::syncState currentStatus play Sep 14 09:51:35 volumio volumio[798]: info: Received an update from plugin. extracting info from payload Sep 14 09:51:35 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:35 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:35 volumio go-librespot[1161]: time="2025-09-14T09:51:35+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Sep 14 09:51:35 volumio go-librespot[1161]: time="2025-09-14T09:51:35+02:00" level=trace msg="emitting websocket event: volume" Sep 14 09:51:35 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:35 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:35 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:35 volumio volumio[798]: info: ControllerMpd::pushState Sep 14 09:51:35 volumio volumio[798]: info: CoreCommandRouter::servicePushState Sep 14 09:51:35 volumio volumio[798]: info: CorePlayQueue::getTrack 0 Sep 14 09:51:35 volumio volumio[798]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366081,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calvin Harris & Jessie Reyez - Ocean","artist":"RMF MAXXX","album":null,"uri":"http://195.150.20.7/rmf_maxxx","trackType":""} Sep 14 09:51:35 volumio volumio[798]: verbose: CURRENT POSITION 0 Sep 14 09:51:35 volumio volumio[798]: info: CoreStateMachine::syncState stateService play Sep 14 09:51:35 volumio volumio[798]: info: CoreStateMachine::syncState currentStatus play Sep 14 09:51:35 volumio volumio[798]: info: Received an update from plugin. extracting info from payload Sep 14 09:51:35 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:35 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:35 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:35 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:35 volumio volumio[798]: info: ------------------------------ 1550ms Sep 14 09:51:35 volumio volumio[798]: info: ------------------------------ 1533ms Sep 14 09:51:35 volumio volumio[798]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 14 09:51:35 volumio volumio[798]: info: CoreCommandRouter::volumioGetState Sep 14 09:51:36 volumio volumio[798]: SPOTIFY: received: {"type":"volume","data":{"value":55,"max":100}} Sep 14 09:51:36 volumio volumio[798]: SPOTIFY: RECEIVED SPOTIFY VOLUME 55 Sep 14 09:51:36 volumio volumio[798]: info: Setting Volumio Volume from Spotify: 55 Sep 14 09:51:36 volumio volumio[798]: info: VolumeController::SetAlsaVolume55 Sep 14 09:51:36 volumio sudo[11025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Sep 14 09:51:36 volumio sudo[11025]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 09:51:36 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:36 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:36 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:36 volumio sudo[11025]: pam_unix(sudo:session): session closed for user root Sep 14 09:51:37 volumio sudo[11037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 Sep 14 09:51:37 volumio sudo[11037]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 09:51:37 volumio sudo[11037]: pam_unix(sudo:session): session closed for user root Sep 14 09:51:37 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:37 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:37 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:37 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:37 volumio volumio[798]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 14 09:51:38 volumio volumio[798]: info: CoreCommandRouter::volumioGetState Sep 14 09:51:38 volumio sudo[11047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=22 pin_b=23 relative_axis=true steps-per-period=4 Sep 14 09:51:38 volumio sudo[11047]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 09:51:38 volumio kernel: rotary-encoder rotary@16: gray Sep 14 09:51:38 volumio kernel: input: rotary@16 as /devices/platform/rotary@16/input/input6 Sep 14 09:51:38 volumio systemd-udevd[11018]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 14 09:51:38 volumio sudo[11047]: pam_unix(sudo:session): session closed for user root Sep 14 09:51:39 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:39 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:39 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:39 volumio volumio-remote-updater[350]: [2025-09-14 09:51:39] [connect] Successful connection Sep 14 09:51:39 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:39 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:40 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:40 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:40 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:40 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:41 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:41 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:41 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:41 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:41 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:42 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:42 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:42 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:43 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:43 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:43 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:43 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:44 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:44 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:45 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:45 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:45 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:45 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:46 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:46 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:46 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:46 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:47 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:47 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:47 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:47 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:48 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:48 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:48 volumio go-librespot[1161]: time="2025-09-14T09:51:48+02:00" level=trace msg="sent dealer ping" Sep 14 09:51:48 volumio go-librespot[1161]: time="2025-09-14T09:51:48+02:00" level=trace msg="received dealer pong" Sep 14 09:51:48 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:48 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:48 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:49 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:49 volumio nmbd[647]: [2025/09/14 09:51:49.458361, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 14 09:51:49 volumio nmbd[647]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.62 for name WORKGROUP<1d>. Sep 14 09:51:49 volumio nmbd[647]: This response was from IP 192.168.1.98, reporting an IP address of 192.168.1.98. Sep 14 09:51:49 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:49 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:50 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:50 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:50 volumio volumio[798]: info: VolumeController::SetAlsaVolume- Sep 14 09:51:50 volumio volumio[798]: info: VolumeController::SetAlsaVolume+ Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:51 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:51 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:52 volumio volumio[798]: info: CoreStateMachine::pushState Sep 14 09:51:52 volumio volumio[798]: info: CoreCommandRouter::volumioPushState Sep 14 09:51:53 volumio volumio[798]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 09:51:53 volumio volumio[798]: Error: Unable to resolve or reject the same promise twice Sep 14 09:51:53 volumio volumio[798]: at Promise.resolve (/data/plugins/system_hardware/rotaryencoder2/node_modules/kew/kew.js:140:43) Sep 14 09:51:53 volumio volumio[798]: at /data/plugins/system_hardware/rotaryencoder2/index.js:999:23 Sep 14 09:51:53 volumio volumio[798]: at FSReqCallback.oncomplete (fs.js:171:23) Sep 14 09:51:53 volumio volumio[798]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 09:51:53 volumio sudo[11133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=27 active_low=true gpio_pull=up keycode=20 Sep 14 09:51:53 volumio sudo[11133]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 09:51:54 volumio kernel: input: button@1b as /devices/platform/button@1b/input/input7 Sep 14 09:51:54 volumio sudo[11133]: pam_unix(sudo:session): session closed for user root Sep 14 09:51:54 volumio volumio-remote-updater[350]: [2025-09-14 09:51:54] [connect] Successful connection Sep 14 09:51:54 volumio systemd-udevd[11142]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 14 09:52:00 volumio sudo[11182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 09:51 Sep 14 09:52:00 volumio sudo[11182]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"