-- Logs begin at Wed 2024-05-08 21:12:54 CEST, end at Wed 2024-05-08 21:15:21 CEST. -- May 08 21:14:00 minerva go-librespot[1229]: time="2024-05-08T21:14:00+02:00" level=debug msg="update volume to 16383/65535" May 08 21:14:00 minerva volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds May 08 21:14:00 minerva volumio[973]: info: sendMpdCommand playlistinfo took 4 milliseconds May 08 21:14:00 minerva volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds May 08 21:14:00 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:14:00 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:14:00 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:14:00 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:14:00 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:14:00 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:14:00 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Nächste Sendung: Tipps für Ö1 Club-Mitglieder","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:14:00 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:14:00 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:00 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:14:00 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:00 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:00 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:14:00 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:14:00 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:14:00 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Nächste Sendung: Tipps für Ö1 Club-Mitglieder","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:14:00 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:14:00 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:00 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:00 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:00 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:14:00 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:14:00 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:14:00 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Nächste Sendung: Tipps für Ö1 Club-Mitglieder","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:14:00 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:14:00 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:00 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:00 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:00 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:00 minerva go-librespot[1229]: time="2024-05-08T21:14:00+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 08 21:14:00 minerva go-librespot[1229]: time="2024-05-08T21:14:00+02:00" level=trace msg="emitting websocket event: volume" May 08 21:14:00 minerva volumio[973]: info: ------------------------------ 433ms May 08 21:14:00 minerva volumio[973]: info: ------------------------------ 275ms May 08 21:14:00 minerva volumio[973]: info: ------------------------------ 274ms May 08 21:14:00 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:00 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:00 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:00 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:00 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:00 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:00 minerva volumio[973]: SPOTIFY: received: {"type":"volume","data":{"value":25,"max":100}} May 08 21:14:00 minerva volumio[973]: SPOTIFY: RECEIVED SPOTIFY VOLUME 25 May 08 21:14:06 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 21:14:06 minerva volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 May 08 21:14:06 minerva volumio[973]: info: CoreCommandRouter::volumioGetState May 08 21:14:07 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 21:14:07 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:14:07 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 21:14:07 minerva volumio-remote-updater[539]: No test mode May 08 21:14:07 minerva volumio-remote-updater[539]: No alpha test mode May 08 21:14:07 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 08 21:14:07 minerva volumio[973]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 08 21:14:07 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 08 21:14:08 minerva volumio[973]: info: May 08 21:14:08 minerva volumio[973]: ---------------------------- MPD announces system playlist update May 08 21:14:08 minerva volumio[973]: info: Ignoring MPD Status Update May 08 21:14:08 minerva volumio[973]: info: May 08 21:14:08 minerva volumio[973]: ---------------------------- MPD announces state update: player May 08 21:14:08 minerva volumio[973]: info: ControllerMpd::getState May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:14:08 minerva volumio[973]: info: May 08 21:14:08 minerva volumio[973]: ---------------------------- MPD announces system playlist update May 08 21:14:08 minerva volumio[973]: info: Ignoring MPD Status Update May 08 21:14:08 minerva volumio[973]: info: May 08 21:14:08 minerva volumio[973]: ---------------------------- MPD announces state update: player May 08 21:14:08 minerva volumio[973]: info: ControllerMpd::getState May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:14:08 minerva volumio[973]: info: May 08 21:14:08 minerva volumio[973]: ---------------------------- MPD announces system playlist update May 08 21:14:08 minerva volumio[973]: info: Ignoring MPD Status Update May 08 21:14:08 minerva volumio[973]: info: May 08 21:14:08 minerva volumio[973]: ---------------------------- MPD announces state update: player May 08 21:14:08 minerva volumio[973]: info: ControllerMpd::getState May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:14:08 minerva volumio[973]: info: ------------------------------ 11ms May 08 21:14:08 minerva volumio[973]: info: sendMpdCommand status took 9 milliseconds May 08 21:14:08 minerva volumio[973]: info: ------------------------------ 9ms May 08 21:14:08 minerva volumio[973]: info: sendMpdCommand status took 7 milliseconds May 08 21:14:08 minerva volumio[973]: info: ------------------------------ 7ms May 08 21:14:08 minerva volumio[973]: info: sendMpdCommand status took 5 milliseconds May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:14:08 minerva volumio[973]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 21:14:08 minerva volumio[973]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 21:14:08 minerva volumio[973]: info: sendMpdCommand playlistinfo took 2 milliseconds May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:14:08 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:14:08 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:14:08 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:14:08 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:14:08 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Fragen zum Ö1 Programm? Tel. (01) 501 70 371","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:14:08 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:14:08 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:08 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:14:08 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:08 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:08 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:14:08 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:14:08 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:14:08 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Fragen zum Ö1 Programm? Tel. (01) 501 70 371","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:14:08 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:14:08 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:08 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:08 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:08 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:14:08 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:14:08 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:14:08 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Fragen zum Ö1 Programm? Tel. (01) 501 70 371","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:14:08 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:14:08 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:08 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:08 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:08 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:08 minerva volumio[973]: info: ------------------------------ 132ms May 08 21:14:08 minerva volumio[973]: info: ------------------------------ 130ms May 08 21:14:08 minerva volumio[973]: info: ------------------------------ 128ms May 08 21:14:08 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:08 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:08 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:08 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:08 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:08 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:10 minerva ntpd[693]: Soliciting pool server 162.159.200.123 May 08 21:14:10 minerva ntpd[693]: Soliciting pool server 141.255.175.252 May 08 21:14:10 minerva ntpd[693]: Soliciting pool server 193.171.23.163 May 08 21:14:11 minerva ntpd[693]: Soliciting pool server 151.236.30.71 May 08 21:14:11 minerva ntpd[693]: Soliciting pool server 144.76.197.108 May 08 21:14:11 minerva ntpd[693]: Soliciting pool server 91.206.8.36 May 08 21:14:11 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:14:11 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:14:11 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 08 21:14:11 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 08 21:14:11 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 08 21:14:11 minerva volumio[973]: info: CoreCommandRouter::volumioGetBrowseSources May 08 21:14:11 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 21:14:12 minerva ntpd[693]: Soliciting pool server 86.59.80.170 May 08 21:14:12 minerva ntpd[693]: Soliciting pool server 152.53.15.127 May 08 21:14:12 minerva ntpd[693]: Soliciting pool server 91.206.8.34 May 08 21:14:13 minerva ntpd[693]: Soliciting pool server 86.59.113.124 May 08 21:14:13 minerva ntpd[693]: Soliciting pool server 83.215.130.11 May 08 21:14:13 minerva ntpd[693]: Soliciting pool server 91.206.8.70 May 08 21:14:13 minerva ntpd[693]: Soliciting pool server 217.196.145.42 May 08 21:14:13 minerva volumio[973]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 08 21:14:13 minerva volumio[973]: info: Completed starting MyVolumio Plugin May 08 21:14:13 minerva volumio[973]: info: BOOT COMPLETED May 08 21:14:13 minerva volumio[973]: [Metrics] CommandRouter: 50s 577.73ms May 08 21:14:13 minerva volumio[973]: info: CoreCommandRouter::volumiosetStartupVolume May 08 21:14:13 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:14:13 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:14:13 minerva volumio[973]: info: CoreCommandRouter::Close All Modals sent May 08 21:14:13 minerva volumio[973]: info: CoreCommandRouter::Close All Modals sent May 08 21:14:14 minerva ntpd[693]: Soliciting pool server 162.159.200.1 May 08 21:14:14 minerva ntpd[693]: Soliciting pool server 2001:628:21f0:80::80:160 May 08 21:14:14 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 08 21:14:14 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:14:14 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 08 21:14:15 minerva ntpd[693]: Soliciting pool server 83.137.41.10 May 08 21:14:16 minerva ntpd[693]: Soliciting pool server 46.102.157.67 May 08 21:14:34 minerva ntpd[693]: receive: Unexpected origin timestamp 0xe9e64d89.5f5dca0a does not match aorg 0000000000.00000000 from server@141.255.175.252 xmt 0xe9e64d9a.81337728 May 08 21:14:34 minerva ntpd[693]: receive: Unexpected origin timestamp 0xe9e64d89.5f50fd85 does not match aorg 0000000000.00000000 from server@83.137.41.10 xmt 0xe9e64d9a.812f390b May 08 21:14:34 minerva ntpd[693]: receive: Unexpected origin timestamp 0xe9e64d89.5f613130 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xe9e64d9a.823a18e2 May 08 21:14:34 minerva ntpd[693]: receive: Unexpected origin timestamp 0xe9e64d89.5f542bed does not match aorg 0000000000.00000000 from server@217.196.145.42 xmt 0xe9e64d9a.812aa49f May 08 21:14:34 minerva ntpd[693]: receive: Unexpected origin timestamp 0xe9e64d89.5f57f014 does not match aorg 0000000000.00000000 from server@152.53.15.127 xmt 0xe9e64d9a.822a655c May 08 21:14:34 minerva ntpd[693]: receive: Unexpected origin timestamp 0xe9e64d89.5f5301f9 does not match aorg 0000000000.00000000 from server@83.215.130.11 xmt 0xe9e64d9a.84f5cfca May 08 21:14:38 minerva volumio[973]: warn: [ROTARYENCODER2] Push Button 1 signalled "released" without intermediate "pressed". You may be suffering from bouncy buttons. May 08 21:14:38 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 1 released after 1715195678389ms. May 08 21:14:38 minerva volumio[973]: warn: [ROTARYENCODER2] Push Button 1 released after 1715195678389ms. Seems quite long, maybe you have a wrong button logic level setting or bouncy button? May 08 21:14:55 minerva volumio[973]: info: May 08 21:14:55 minerva volumio[973]: ---------------------------- MPD announces system playlist update May 08 21:14:55 minerva volumio[973]: info: Ignoring MPD Status Update May 08 21:14:55 minerva volumio[973]: info: May 08 21:14:55 minerva volumio[973]: ---------------------------- MPD announces state update: player May 08 21:14:55 minerva volumio[973]: info: ControllerMpd::getState May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:14:55 minerva volumio[973]: info: May 08 21:14:55 minerva volumio[973]: ---------------------------- MPD announces system playlist update May 08 21:14:55 minerva volumio[973]: info: Ignoring MPD Status Update May 08 21:14:55 minerva volumio[973]: info: May 08 21:14:55 minerva volumio[973]: ---------------------------- MPD announces state update: player May 08 21:14:55 minerva volumio[973]: info: ControllerMpd::getState May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:14:55 minerva volumio[973]: info: May 08 21:14:55 minerva volumio[973]: ---------------------------- MPD announces system playlist update May 08 21:14:55 minerva volumio[973]: info: Ignoring MPD Status Update May 08 21:14:55 minerva volumio[973]: info: May 08 21:14:55 minerva volumio[973]: ---------------------------- MPD announces state update: player May 08 21:14:55 minerva volumio[973]: info: ControllerMpd::getState May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:14:55 minerva volumio[973]: info: ------------------------------ 15ms May 08 21:14:55 minerva volumio[973]: info: sendMpdCommand status took 12 milliseconds May 08 21:14:55 minerva volumio[973]: info: ------------------------------ 12ms May 08 21:14:55 minerva volumio[973]: info: sendMpdCommand status took 9 milliseconds May 08 21:14:55 minerva volumio[973]: info: ------------------------------ 8ms May 08 21:14:55 minerva volumio[973]: info: sendMpdCommand status took 6 milliseconds May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:14:55 minerva volumio[973]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 21:14:55 minerva volumio[973]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 21:14:55 minerva volumio[973]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:14:55 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:14:55 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:14:55 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:14:55 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:14:55 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40104,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jetzt in Ö1: Wir Opportunist:innen","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:14:55 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:14:55 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:55 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:14:55 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:55 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:55 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:14:55 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:14:55 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:14:55 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40104,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jetzt in Ö1: Wir Opportunist:innen","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:14:55 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:14:55 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:55 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:55 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:55 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:14:55 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:14:55 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:14:55 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40104,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jetzt in Ö1: Wir Opportunist:innen","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:14:55 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:14:55 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:55 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:55 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:14:55 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:14:55 minerva volumio[973]: info: ------------------------------ 131ms May 08 21:14:55 minerva volumio[973]: info: ------------------------------ 129ms May 08 21:14:55 minerva volumio[973]: info: ------------------------------ 127ms May 08 21:14:56 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:56 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:56 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:56 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:56 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:14:56 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:15:10 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 pressed. May 08 21:15:10 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 starting timers. May 08 21:15:10 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 doublepush timer elapsed. (true, 1) May 08 21:15:11 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 released after 1150ms. May 08 21:15:11 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 sending single push command at release. May 08 21:15:11 minerva volumio[973]: info: [ROTARYENCODER2] emitPushCommand: 10for Rotary: 2 May 08 21:15:11 minerva volumio[973]: info: VolumeController::SetAlsaVolumemute May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:11 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 08 21:15:11 minerva volumio[973]: SPOTIFY: SPOTIFY VOLUME 25 May 08 21:15:11 minerva volumio[973]: SPOTIFY: VOLUMIO VOLUME 0 May 08 21:15:11 minerva volumio[973]: SPOTIFY: DELTA VOLUME ENOUGH: true May 08 21:15:11 minerva volumio[973]: info: Setting Spotify Volume from Volumio: 0 May 08 21:15:11 minerva volumio[973]: info: May 08 21:15:11 minerva volumio[973]: ---------------------------- MPD announces state update: mixer May 08 21:15:11 minerva volumio[973]: info: ControllerMpd::getState May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:15:11 minerva volumio[973]: info: May 08 21:15:11 minerva volumio[973]: ---------------------------- MPD announces state update: mixer May 08 21:15:11 minerva volumio[973]: info: ControllerMpd::getState May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:15:11 minerva volumio[973]: info: May 08 21:15:11 minerva volumio[973]: ---------------------------- MPD announces state update: mixer May 08 21:15:11 minerva volumio[973]: info: ControllerMpd::getState May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:15:11 minerva volumio[973]: info: sendMpdCommand status took 22 milliseconds May 08 21:15:11 minerva volumio[973]: info: sendMpdCommand status took 15 milliseconds May 08 21:15:11 minerva volumio[973]: info: sendMpdCommand status took 8 milliseconds May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:15:11 minerva volumio[973]: info: sendMpdCommand playlistinfo took 7 milliseconds May 08 21:15:11 minerva volumio[973]: info: sendMpdCommand playlistinfo took 6 milliseconds May 08 21:15:11 minerva volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:15:11 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:15:11 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:15:11 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:15:11 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55488,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jetzt in Ö1: Wir Opportunist:innen","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:15:11 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:15:11 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:11 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:15:11 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:15:11 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55488,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jetzt in Ö1: Wir Opportunist:innen","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:15:11 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:15:11 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:11 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:15:11 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:15:11 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55488,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jetzt in Ö1: Wir Opportunist:innen","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:15:11 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:15:11 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:11 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:11 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:11 minerva volumio[973]: info: ------------------------------ 171ms May 08 21:15:11 minerva volumio[973]: info: ------------------------------ 165ms May 08 21:15:11 minerva volumio[973]: info: ------------------------------ 158ms May 08 21:15:11 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 08 21:15:11 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 08 21:15:11 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 08 21:15:11 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 08 21:15:11 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 08 21:15:11 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 May 08 21:15:11 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 longpush timer elapsed. (false, 0) May 08 21:15:12 minerva volumio[973]: SPOTIFY: SETTING SPOTIFY VOLUME 0 May 08 21:15:12 minerva volumio[973]: info: Sending Spotify command with payload to local API: /player/volume May 08 21:15:12 minerva go-librespot[1229]: time="2024-05-08T21:15:12+02:00" level=debug msg="update volume to 0/65535" May 08 21:15:12 minerva go-librespot[1229]: time="2024-05-08T21:15:12+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 08 21:15:12 minerva go-librespot[1229]: time="2024-05-08T21:15:12+02:00" level=trace msg="emitting websocket event: volume" May 08 21:15:12 minerva volumio[973]: SPOTIFY: received: {"type":"volume","data":{"value":0,"max":100}} May 08 21:15:12 minerva volumio[973]: SPOTIFY: RECEIVED SPOTIFY VOLUME 0 May 08 21:15:17 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 pressed. May 08 21:15:17 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 starting timers. May 08 21:15:17 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 released after 105ms. May 08 21:15:18 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 doublepush timer elapsed. (false, 1) May 08 21:15:18 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 sending single push command. May 08 21:15:18 minerva volumio[973]: info: [ROTARYENCODER2] emitPushCommand: 10for Rotary: 2 May 08 21:15:18 minerva volumio[973]: info: VolumeController::SetAlsaVolumeunmute May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:18 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:15:18 minerva volumio[973]: SPOTIFY: SPOTIFY VOLUME 0 May 08 21:15:18 minerva volumio[973]: SPOTIFY: VOLUMIO VOLUME 25 May 08 21:15:18 minerva volumio[973]: SPOTIFY: DELTA VOLUME ENOUGH: true May 08 21:15:18 minerva volumio[973]: info: Setting Spotify Volume from Volumio: 25 May 08 21:15:18 minerva volumio[973]: info: May 08 21:15:18 minerva volumio[973]: ---------------------------- MPD announces state update: mixer May 08 21:15:18 minerva volumio[973]: info: ControllerMpd::getState May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:15:18 minerva volumio[973]: info: May 08 21:15:18 minerva volumio[973]: ---------------------------- MPD announces state update: mixer May 08 21:15:18 minerva volumio[973]: info: ControllerMpd::getState May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:15:18 minerva volumio[973]: info: May 08 21:15:18 minerva volumio[973]: ---------------------------- MPD announces state update: mixer May 08 21:15:18 minerva volumio[973]: info: ControllerMpd::getState May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand status May 08 21:15:18 minerva volumio[973]: info: sendMpdCommand status took 15 milliseconds May 08 21:15:18 minerva volumio[973]: info: sendMpdCommand status took 11 milliseconds May 08 21:15:18 minerva volumio[973]: info: sendMpdCommand status took 6 milliseconds May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::parseState May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:15:18 minerva volumio[973]: info: sendMpdCommand playlistinfo took 7 milliseconds May 08 21:15:18 minerva volumio[973]: info: sendMpdCommand playlistinfo took 6 milliseconds May 08 21:15:18 minerva volumio[973]: info: sendMpdCommand playlistinfo took 8 milliseconds May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:15:18 minerva volumio[973]: verbose: ControllerMpd::parseTrackInfo May 08 21:15:18 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:15:18 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:15:18 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62607,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jetzt in Ö1: Wir Opportunist:innen","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:15:18 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:15:18 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:18 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:15:18 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:15:18 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62607,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jetzt in Ö1: Wir Opportunist:innen","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:15:18 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:15:18 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:18 minerva volumio[973]: info: ControllerMpd::pushState May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::servicePushState May 08 21:15:18 minerva volumio[973]: info: CorePlayQueue::getTrack 0 May 08 21:15:18 minerva volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62607,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Jetzt in Ö1: Wir Opportunist:innen","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:15:18 minerva volumio[973]: verbose: CURRENT POSITION 0 May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::syncState stateService play May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::syncState currentStatus play May 08 21:15:18 minerva volumio[973]: info: Received an update from plugin. extracting info from payload May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:18 minerva volumio[973]: info: CoreStateMachine::pushState May 08 21:15:18 minerva volumio[973]: info: CoreCommandRouter::volumioPushState May 08 21:15:18 minerva volumio[973]: info: ------------------------------ 177ms May 08 21:15:18 minerva volumio[973]: info: ------------------------------ 170ms May 08 21:15:18 minerva volumio[973]: info: ------------------------------ 165ms May 08 21:15:18 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:15:18 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:15:18 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:15:18 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:15:18 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:15:18 minerva volumio[973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:15:19 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 pressed. May 08 21:15:19 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 starting timers. May 08 21:15:19 minerva volumio[973]: SPOTIFY: SETTING SPOTIFY VOLUME 25 May 08 21:15:19 minerva volumio[973]: info: Sending Spotify command with payload to local API: /player/volume May 08 21:15:19 minerva go-librespot[1229]: time="2024-05-08T21:15:19+02:00" level=debug msg="update volume to 16383/65535" May 08 21:15:19 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 doublepush timer elapsed. (true, 1) May 08 21:15:19 minerva go-librespot[1229]: time="2024-05-08T21:15:19+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 08 21:15:19 minerva go-librespot[1229]: time="2024-05-08T21:15:19+02:00" level=trace msg="emitting websocket event: volume" May 08 21:15:19 minerva volumio[973]: SPOTIFY: received: {"type":"volume","data":{"value":25,"max":100}} May 08 21:15:19 minerva volumio[973]: SPOTIFY: RECEIVED SPOTIFY VOLUME 25 May 08 21:15:20 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 longpush timer elapsed. (true, 1) May 08 21:15:20 minerva volumio[973]: info: [ROTARYENCODER2] Push Button 2 sending long push command. May 08 21:15:20 minerva volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 21:15:20 minerva volumio[973]: SyntaxError: Unexpected end of JSON input May 08 21:15:20 minerva volumio[973]: at JSON.parse () May 08 21:15:20 minerva volumio[973]: at rotaryencoder2.emitPushCommand (/data/plugins/system_hardware/rotaryencoder2/index.js:751:17) May 08 21:15:20 minerva volumio[973]: at Timeout._onTimeout (/data/plugins/system_hardware/rotaryencoder2/index.js:553:18) May 08 21:15:20 minerva volumio[973]: at listOnTimeout (internal/timers.js:554:17) May 08 21:15:20 minerva volumio[973]: at processTimers (internal/timers.js:497:7) May 08 21:15:20 minerva volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 21:15:21 minerva sudo[1996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 21:14 May 08 21:15:21 minerva sudo[1996]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"