-- Logs begin at Sun 2024-05-12 11:53:32 CEST, end at Sun 2024-05-12 12:24:41 CEST. -- May 12 12:23:00 minerva sudo[5165]: pam_unix(sudo:session): session closed for user root May 12 12:23:00 minerva mpd[5098]: May 12 12:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 12:23:00 minerva volumio[4964]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 12 12:23:00 minerva volumio[4964]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 12 12:23:00 minerva volumio[4964]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 12 12:23:00 minerva volumio[4964]: info: Starting Shairport Sync May 12 12:23:00 minerva volumio[4964]: info: Starting Shairport Sync May 12 12:23:00 minerva sudo[5183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 12:23:00 minerva sudo[5183]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:23:00 minerva volumio[4964]: info: Starting Shairport Sync May 12 12:23:00 minerva systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 12 12:23:00 minerva sudo[5186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 12:23:00 minerva sudo[5186]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:23:00 minerva sudo[5188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 12:23:00 minerva sudo[5188]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:23:00 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:00 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:00 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:00 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:00 minerva sudo[5233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 12 12:23:00 minerva sudo[5233]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:23:00 minerva volumio[4964]: SPOTIFY: User informations: {"display_name":"Stephan Köstinger","external_urls":{"spotify":"https://open.spotify.com/user/stekst"},"href":"https://api.spotify.com/v1/users/stekst","id":"stekst","images":[{"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t1.18169-1/11060991_936550113036391_5475360604895385030_n.jpg?stp=cp0_dst-jpg_p50x50&_nc_cat=109&ccb=1-7&_nc_sid=5f2048&_nc_ohc=t9_v9c7t51wQ7kNvgGRcm7h&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&oh=00_AYDL3p44sQdQbYt24FnTtZZNmRAwi-OFBtRPxWf607QvNQ&oe=666785EC","height":64,"width":64},{"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t1.18169-1/11060991_936550113036391_5475360604895385030_n.jpg?stp=dst-jpg_s320x320&_nc_cat=109&ccb=1-7&_nc_sid=5f2048&_nc_ohc=t9_v9c7t51wQ7kNvgGRcm7h&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&oh=00_AYDgUcKzVJg51uVJIjiJ8bW-nWGwQ-MOwVopnyHAwOc3Sw&oe=666785EC","height":300,"width":300}],"type":"user","uri":"spotify:user:stekst","followers":{"href":null,"total":6},"country":"AT","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"herbert.geier@gmail.com"} May 12 12:23:00 minerva volumio[4964]: info: Spotify Successfully logged in May 12 12:23:00 minerva volumio[4964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 12:23:00 minerva volumio[4964]: info: [1715509380816] CoreMusicLibrary::Adding element Spotify May 12 12:23:00 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 12:23:00 minerva volumio[4964]: Cannot find translation for source YouTube2 May 12 12:23:00 minerva volumio[4964]: Cannot find translation for source 80s80s Radio May 12 12:23:00 minerva volumio[4964]: Cannot find translation for source Spotify May 12 12:23:00 minerva systemd[1]: Started Music Player Daemon. May 12 12:23:00 minerva sudo[5123]: pam_unix(sudo:session): session closed for user root May 12 12:23:00 minerva sudo[5085]: pam_unix(sudo:session): session closed for user root May 12 12:23:00 minerva sudo[5095]: pam_unix(sudo:session): session closed for user root May 12 12:23:01 minerva systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 12:23:01 minerva systemd[1]: Stopping go-librespot Daemon... May 12 12:23:01 minerva systemd[1]: shairport-sync.service: Succeeded. May 12 12:23:01 minerva systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 12:23:01 minerva volumio[4964]: info: touch_display: systemctl daemon-reload succeeded. May 12 12:23:01 minerva systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 12 12:23:01 minerva systemd[1]: go-librespot-daemon.service: Succeeded. May 12 12:23:01 minerva systemd[1]: Stopped go-librespot Daemon. May 12 12:23:01 minerva sudo[5233]: pam_unix(sudo:session): session closed for user root May 12 12:23:01 minerva sudo[5239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 12 12:23:01 minerva sudo[5239]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:23:01 minerva systemd[1]: Started go-librespot Daemon. May 12 12:23:01 minerva go-librespot[5237]: Librespot-go daemon starting... May 12 12:23:01 minerva systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 12:23:01 minerva sudo[5148]: pam_unix(sudo:session): session closed for user root May 12 12:23:01 minerva sudo[5188]: pam_unix(sudo:session): session closed for user root May 12 12:23:01 minerva sudo[5183]: pam_unix(sudo:session): session closed for user root May 12 12:23:01 minerva sudo[5186]: pam_unix(sudo:session): session closed for user root May 12 12:23:01 minerva volumio[4964]: info: MPD running with PID5098 May 12 12:23:01 minerva volumio[4964]: ,establishing connection May 12 12:23:01 minerva go-librespot[5237]: time="2024-05-12T12:23:01+02:00" level=info msg="generated new device id: 4991a58802fd2fc83b3548d7d4e37392c2c1b5a2" May 12 12:23:01 minerva go-librespot[5237]: time="2024-05-12T12:23:01+02:00" level=debug msg="stored credentials found for stekst" May 12 12:23:01 minerva volumio[4964]: info: Upmpdcli Daemon Started May 12 12:23:01 minerva sudo[5239]: pam_unix(sudo:session): session closed for user root May 12 12:23:01 minerva volumio[4964]: error: MPD error: The expression evaluated to a falsy value: May 12 12:23:01 minerva volumio[4964]: assert.ok(self.idling) May 12 12:23:01 minerva volumio[4964]: error: The expression evaluated to a falsy value: May 12 12:23:01 minerva volumio[4964]: assert.ok(self.idling) May 12 12:23:01 minerva volumio[4964]: error: MPD error: The expression evaluated to a falsy value: May 12 12:23:01 minerva volumio[4964]: assert.ok(self.idling) May 12 12:23:01 minerva volumio[4964]: error: The expression evaluated to a falsy value: May 12 12:23:01 minerva volumio[4964]: assert.ok(self.idling) May 12 12:23:01 minerva volumio[4964]: info: Shairport-Sync Started May 12 12:23:01 minerva volumio[4964]: Error adding Membership: Error: addMembership EINVAL May 12 12:23:01 minerva volumio[4964]: info: Shairport-Sync Started May 12 12:23:01 minerva volumio[4964]: info: Shairport-Sync Started May 12 12:23:01 minerva volumio[4964]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 12 12:23:01 minerva volumio[4964]: info: touch_display: Volumio Kiosk started. May 12 12:23:01 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:01 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:01 minerva volumio[4964]: info: Completed starting Core Plugins May 12 12:23:01 minerva volumio[4964]: info: ------------------------------------------- May 12 12:23:01 minerva volumio[4964]: info: ----- MyVolumio plugins startup ---- May 12 12:23:01 minerva volumio[4964]: info: ------------------------------------------- May 12 12:23:01 minerva volumio[4964]: info: [MyVolumio PluginManager] Fetching plans data.... May 12 12:23:01 minerva volumio[4964]: error: updateQueue error: null May 12 12:23:01 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:01 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:01 minerva volumio[4964]: info: touch_display: X display number found: 0 May 12 12:23:01 minerva go-librespot[5237]: time="2024-05-12T12:23:01+02:00" level=debug msg="obtained new client token: AACWkSpBqE9TrO3Rvah5W2njmiyL82xSP3RbySv6fF+QLSwEdp+gGrn6NwzHIsVMLkPtogxpkiZ6TNHbr3poYpGcVMtqrvQjBWNAJYeS4TXD5s8cEAl+ANPBQMMP8UUxLVCszlShvdfWDxiBygZt2DxdtWy3jSUCvqgOlzy48TUlNeFvz40GiICwquUzuZolQvvarQmP6V9t5ByRfx4dlSfm/yLNCSSHyPo6yQ74BTukZRylaAAUdeew5WXSkuHX" May 12 12:23:01 minerva volumio[4964]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 12 12:23:01 minerva volumio[4964]: info: touch_display: X display number found: 0 May 12 12:23:01 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:01 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:01 minerva go-librespot[5237]: time="2024-05-12T12:23:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" May 12 12:23:01 minerva volumio[4964]: info: [now-playing] OpenWeatherMap API key obtained. May 12 12:23:01 minerva go-librespot[5237]: time="2024-05-12T12:23:01+02:00" level=debug msg="completed keyexchange" May 12 12:23:01 minerva volumio[4964]: info: touch_display: Setting screensaver timeout to 0 seconds. May 12 12:23:02 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 12:23:02 minerva volumio[4964]: info: Discovery: Getting this device information May 12 12:23:02 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:02 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:02 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="completed challenge" May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="authenticated as stekst" May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="authenticated as stekst" May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 12 12:23:02 minerva volumio-remote-updater[598]: [2024-05-12 12:23:02] [connect] Successful connection May 12 12:23:02 minerva volumio-remote-updater[598]: [2024-05-12 12:23:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715509382 101 May 12 12:23:02 minerva volumio[4964]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 9 May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="dealer connection opened" May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" May 12 12:23:02 minerva go-librespot[5237]: time="2024-05-12T12:23:02+02:00" level=debug msg="received connection id: NjIzNDY2ZjAtNTUzNy00ZWE3LTgxZjUtZDRjZDhjODkwZDAwK2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLXI2c3QuZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwKzI1M0RCREFDMjFCN0Q2RUY3RDRDQkQxNkVBRUM2NzA3QTcyQ0MxMTgwMEM5OTEzM0EyMTU5Q0U3REQ3QjQ2OEM=" May 12 12:23:03 minerva go-librespot[5237]: time="2024-05-12T12:23:03+02:00" level=debug msg="put connect state because NEW_DEVICE" May 12 12:23:04 minerva volumio[4964]: info: go-librespot daemon successfully initialized May 12 12:23:07 minerva volumio[4964]: info: Initializing connection to go-librespot Websocket May 12 12:23:07 minerva go-librespot[5237]: time="2024-05-12T12:23:07+02:00" level=debug msg="new websocket client" May 12 12:23:07 minerva volumio[4964]: info: Connection to go-librespot Websocket established May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 12 12:23:10 minerva volumio[4964]: info: Adding plugin bluetooth to MyMusic Plugins May 12 12:23:10 minerva volumio[4964]: info: Adding plugin multiroom to MyMusic Plugins May 12 12:23:10 minerva volumio[4964]: info: Adding plugin metavolumio to MyMusic Plugins May 12 12:23:10 minerva volumio[4964]: info: Adding plugin cd_controller to MyMusic Plugins May 12 12:23:10 minerva volumio[4964]: info: Adding plugin smart_inputs to MyMusic Plugins May 12 12:23:10 minerva volumio[4964]: info: Adding plugin tidalconnect to MyMusic Plugins May 12 12:23:10 minerva volumio[4964]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 12 12:23:11 minerva volumio[4964]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 12 12:23:11 minerva volumio[4964]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 12 12:23:11 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 12:23:11 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 12:23:11 minerva volumio[4964]: info: Starting MyVolumio Remote Streaming Endpoints May 12 12:23:11 minerva volumio[4964]: info: MyVolumio login type: Token May 12 12:23:11 minerva volumio[4964]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 12 12:23:11 minerva volumio[4964]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 12 12:23:12 minerva volumio[4964]: info: Starting Streaming Service Transparent Proxy May 12 12:23:12 minerva volumio[4964]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 12 12:23:12 minerva volumio[4964]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 12 12:23:12 minerva volumio[4964]: info: Streaming services startup May 12 12:23:12 minerva volumio[4964]: info: Starting Streaming Daemon May 12 12:23:12 minerva sudo[5268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 12 12:23:12 minerva sudo[5268]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:23:12 minerva sudo[5268]: pam_unix(sudo:session): session closed for user root May 12 12:23:12 minerva volumio[4964]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 12 12:23:12 minerva volumio[4964]: info: Getting Spotify volume May 12 12:23:12 minerva volumio[4964]: error: Cannot start Volumio Streaming Daemon May 12 12:23:12 minerva volumio[4964]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 12 12:23:12 minerva volumio[4964]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 12 12:23:12 minerva volumio[4964]: info: Spotify volume: 100 May 12 12:23:12 minerva volumio[4964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 May 12 12:23:12 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:12 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:12 minerva volumio[4964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 May 12 12:23:12 minerva volumio[4964]: SPOTIFY: SPOTIFY VOLUME 100 May 12 12:23:12 minerva volumio[4964]: SPOTIFY: VOLUMIO VOLUME 20 May 12 12:23:12 minerva volumio[4964]: SPOTIFY: DELTA VOLUME ENOUGH: true May 12 12:23:12 minerva volumio[4964]: info: Setting Spotify Volume from Volumio: 20 May 12 12:23:12 minerva volumio[4964]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 12 12:23:13 minerva volumio[4964]: STREAMING PROXY: Starting server on port 3245 May 12 12:23:13 minerva volumio[4964]: Node JS runtime: 14 May 12 12:23:13 minerva volumio[4964]: info: MyVolumio token set successfully May 12 12:23:13 minerva volumio[4964]: info: MYVOLUMIO: Adding device May 12 12:23:13 minerva volumio[4964]: info: MYVOLUMIO: Evaluating Server May 12 12:23:14 minerva volumio[4964]: info: MyVolumio status changed May 12 12:23:14 minerva volumio[4964]: info: Streaming services startup May 12 12:23:14 minerva volumio[4964]: info: Starting Streaming Daemon May 12 12:23:14 minerva volumio[4964]: info: Removing browser output: myVolumio user plan is not superstar May 12 12:23:14 minerva volumio[4964]: info: Removing audio output: May 12 12:23:14 minerva volumio[4964]: info: Stoppping Tunnel 1 May 12 12:23:14 minerva sudo[5294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 12 12:23:14 minerva sudo[5294]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:23:14 minerva sudo[5294]: pam_unix(sudo:session): session closed for user root May 12 12:23:14 minerva volumio[4964]: SPOTIFY: SETTING SPOTIFY VOLUME 20 May 12 12:23:14 minerva volumio[4964]: info: Sending Spotify command with payload to local API: /player/volume May 12 12:23:14 minerva sudo[5297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 12 12:23:14 minerva sudo[5297]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:23:14 minerva go-librespot[5237]: time="2024-05-12T12:23:14+02:00" level=debug msg="update volume to 13107/65535" May 12 12:23:14 minerva volumio[4964]: error: Cannot start Volumio Streaming Daemon May 12 12:23:14 minerva volumio[4964]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 12 12:23:14 minerva volumio[4964]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 12 12:23:14 minerva sudo[5297]: pam_unix(sudo:session): session closed for user root May 12 12:23:14 minerva volumio[4964]: info: Remote SSH Stopped May 12 12:23:14 minerva go-librespot[5237]: time="2024-05-12T12:23:14+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 12 12:23:14 minerva go-librespot[5237]: time="2024-05-12T12:23:14+02:00" level=trace msg="emitting websocket event: volume" May 12 12:23:14 minerva volumio[4964]: SPOTIFY: received: {"type":"volume","data":{"value":20,"max":100}} May 12 12:23:14 minerva volumio[4964]: SPOTIFY: RECEIVED SPOTIFY VOLUME 20 May 12 12:23:14 minerva volumio[4964]: info: Setting Geolocation for MyVolumio to eu7 May 12 12:23:14 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 12:23:14 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 12:23:14 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 12:23:14 minerva volumio[4964]: info: Successfully Added MyVolumio device May 12 12:23:15 minerva volumio[4964]: info: Updating MyVolumio device info May 12 12:23:15 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 12:23:15 minerva volumio[4964]: info: Successfully Updated MyVolumio device May 12 12:23:26 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 12 12:23:26 minerva volumio[4964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 May 12 12:23:26 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:26 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:28 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 12:23:28 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 12:23:28 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 12 12:23:28 minerva volumio-remote-updater[598]: No test mode May 12 12:23:28 minerva volumio-remote-updater[598]: No alpha test mode May 12 12:23:28 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 12 12:23:28 minerva volumio[4964]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 12 12:23:28 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 12 12:23:31 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 12:23:31 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 12:23:31 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 12 12:23:31 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 12 12:23:31 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 12 12:23:31 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 12 12:23:31 minerva volumio[4964]: info: CoreCommandRouter::volumioGetBrowseSources May 12 12:23:31 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 12:23:32 minerva volumio[4964]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 12 12:23:32 minerva volumio[4964]: info: Completed starting MyVolumio Plugin May 12 12:23:32 minerva volumio[4964]: info: BOOT COMPLETED May 12 12:23:32 minerva volumio[4964]: [Metrics] CommandRouter: 44s 791.66ms May 12 12:23:32 minerva volumio[4964]: info: CoreCommandRouter::volumiosetStartupVolume May 12 12:23:32 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 12:23:32 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 12:23:32 minerva volumio[4964]: info: CoreCommandRouter::Close All Modals sent May 12 12:23:32 minerva volumio[4964]: info: CoreCommandRouter::Close All Modals sent May 12 12:23:32 minerva kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 12 12:23:33 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 12 12:23:33 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 12 12:23:33 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 12 12:23:52 minerva volumio[4964]: verbose: New Socket.io Connection to 192.168.1.167 from 192.168.1.157 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36 Total Clients: 12 May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::volumioGetVisibleSources May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:52 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:52 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 12 12:23:52 minerva volumio[4964]: info: Received Get System Info May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 12:23:52 minerva volumio[4964]: info: Discovery: Getting this device information May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:52 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:23:52 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:23:52 minerva volumio[4964]: info: Listing playlists May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::volumioGetQueue May 12 12:23:52 minerva volumio[4964]: info: CoreStateMachine::getQueue May 12 12:23:52 minerva volumio[4964]: info: CorePlayQueue::getQueue May 12 12:23:52 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 12 12:24:06 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 12 12:24:06 minerva volumio[4964]: info: Received Get System Info May 12 12:24:06 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 12 12:24:06 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 12 12:24:06 minerva volumio[4964]: info: Discovery: Getting this device information May 12 12:24:06 minerva volumio[4964]: info: CoreCommandRouter::volumioGetState May 12 12:24:06 minerva volumio[4964]: info: CorePlayQueue::getTrack 0 May 12 12:24:06 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 12:24:17 minerva volumio[4964]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] May 12 12:24:17 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] updateEncoder: Rotary2 with:{"enabled1":true,"rotaryType1":{"value":2,"label":"1/2"},"pinA1":15,"pinB1":23,"dialAction1":{"value":2,"label":"Titel vor/zurück"},"socketCmdCCW1":"","socketDataCCW1":"","socketCmdCW1":"","socketDataCW1":"","pinPush1":13,"pinPushDebounce1":10,"pushState1":true,"pushAction1":{"value":13,"label":"Websocket Nachricht senden"},"socketCmdPush1":"volume","socketDataPush1":"+","longPushAction1":{"value":0,"label":"..."},"socketCmdLongPush1":"listPlaylist","socketDataLongPush1":"","delayLongPush1":"1500","doublePushAction1":{"value":0,"label":"..."},"socketCmdDoublePush1":"","socketDataDoublePush1":"","delayDoublePush1":"700"} May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] sanityCheckSettings: Rotary2 for:{"enabled1":true,"rotaryType1":{"value":2,"label":"1/2"},"pinA1":15,"pinB1":23,"dialAction1":{"value":2,"label":"Titel vor/zurück"},"socketCmdCCW1":"","socketDataCCW1":"","socketCmdCW1":"","socketDataCW1":"","pinPush1":13,"pinPushDebounce1":10,"pushState1":true,"pushAction1":{"value":13,"label":"Websocket Nachricht senden"},"socketCmdPush1":"volume","socketDataPush1":"+","longPushAction1":{"value":0,"label":"..."},"socketCmdLongPush1":"listPlaylist","socketDataLongPush1":"","delayLongPush1":"1500","doublePushAction1":{"value":0,"label":"..."},"socketCmdDoublePush1":"","socketDataDoublePush1":"","delayDoublePush1":"700"} May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] sanityCheckSettings: allPins:16,12,6,15,23,13 May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: 1,2,3 May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: 2,3 May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: 3 May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: end of recursion. May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] detachListener: null May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] detachListener: no handler process to kill May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 2 May 12 12:24:17 minerva sudo[5359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 12 12:24:17 minerva sudo[5359]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:17 minerva sudo[5359]: pam_unix(sudo:session): session closed for user root May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=15pinB=23 in Overlays (in load order): May 12 12:24:17 minerva volumio[4964]: 0: rotary-encoder pin_a=16 pin_b=12 relative_axis=true steps-per-period=2 May 12 12:24:17 minerva volumio[4964]: 1: rotary-encoder pin_a=15 pin_b=23 relative_axis=true steps-per-period=2 May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: rotary 2uses overlay 1 May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: 1 May 12 12:24:17 minerva sudo[5362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 1 May 12 12:24:17 minerva sudo[5362]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:17 minerva sudo[5362]: pam_unix(sudo:session): session closed for user root May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: 1 returned: May 12 12:24:17 minerva sudo[5373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 12 12:24:17 minerva sudo[5373]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:17 minerva sudo[5373]: pam_unix(sudo:session): session closed for user root May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: "overlay -l" returned: Overlays (in load order): May 12 12:24:17 minerva volumio[4964]: 0: rotary-encoder pin_a=16 pin_b=12 relative_axis=true steps-per-period=2 May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: deactivated rotary2 May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] detachListener: null May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] detachListener: no handler process to kill May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 1 May 12 12:24:17 minerva sudo[5376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 12 12:24:17 minerva sudo[5376]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:17 minerva sudo[5376]: pam_unix(sudo:session): session closed for user root May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=16pinB=12 in Overlays (in load order): May 12 12:24:17 minerva volumio[4964]: 0: rotary-encoder pin_a=16 pin_b=12 relative_axis=true steps-per-period=2 May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: rotary 1uses overlay 0 May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: 0 May 12 12:24:17 minerva sudo[5379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 12 12:24:17 minerva sudo[5379]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:17 minerva sudo[5379]: pam_unix(sudo:session): session closed for user root May 12 12:24:17 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: 0 returned: May 12 12:24:18 minerva sudo[5388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 12 12:24:18 minerva sudo[5388]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:18 minerva sudo[5388]: pam_unix(sudo:session): session closed for user root May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: "overlay -l" returned: No overlays loaded May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: deactivated rotary1 May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: 1,2,3 May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: 2,3 May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: 3 May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: end of recursion. May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: button 3 is not configured. May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: deactivated button 2.([object Object]) May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: deactivated button 1.([object Object]) May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] updateEncoder: Changing Encoder 2 Settings to new values May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] activateRotaries: 1,2,3 May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] activateRotaries: 1,2 May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] activateRotaries: 1 May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] activateRotaries: May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] activateRotaries: end of recursion. May 12 12:24:18 minerva volumio[4964]: info: [ROTARYENCODER2] addOverlay: 16 12 2 May 12 12:24:18 minerva sudo[5391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=16 pin_b=12 relative_axis=true steps-per-period=2 May 12 12:24:18 minerva sudo[5391]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:18 minerva kernel: rotary-encoder rotary@10: gray May 12 12:24:18 minerva kernel: input: rotary@10 as /devices/platform/rotary@10/input/input9 May 12 12:24:18 minerva sudo[5391]: pam_unix(sudo:session): session closed for user root May 12 12:24:18 minerva volumio[4964]: info: /dev/input: May 12 12:24:18 minerva volumio[4964]: by-path May 12 12:24:18 minerva volumio[4964]: event0 May 12 12:24:18 minerva volumio[4964]: event1 May 12 12:24:18 minerva volumio[4964]: mice May 12 12:24:18 minerva volumio[4964]: mouse0 May 12 12:24:18 minerva volumio[4964]: /dev/input/by-path: May 12 12:24:18 minerva volumio[4964]: platform-3f204000.spi-event May 12 12:24:18 minerva systemd-udevd[5369]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 12 12:24:19 minerva volumio[4964]: info: [ROTARYENCODER2] attachListener: /dev/input/by-path/platform-rotary@10-event May 12 12:24:19 minerva volumio[4964]: info: [ROTARYENCODER2] addEventHandle for rotary: 1 May 12 12:24:19 minerva volumio[4964]: info: [ROTARYENCODER2] addOverlay: 15 23 2 May 12 12:24:19 minerva sudo[5407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=15 pin_b=23 relative_axis=true steps-per-period=2 May 12 12:24:19 minerva sudo[5407]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:19 minerva kernel: rotary-encoder rotary@f: gray May 12 12:24:19 minerva kernel: input: rotary@f as /devices/platform/rotary@f/input/input10 May 12 12:24:19 minerva sudo[5407]: pam_unix(sudo:session): session closed for user root May 12 12:24:19 minerva volumio[4964]: info: /dev/input: May 12 12:24:19 minerva volumio[4964]: by-path May 12 12:24:19 minerva volumio[4964]: event0 May 12 12:24:19 minerva volumio[4964]: event1 May 12 12:24:19 minerva volumio[4964]: event2 May 12 12:24:19 minerva volumio[4964]: mice May 12 12:24:19 minerva volumio[4964]: mouse0 May 12 12:24:19 minerva volumio[4964]: /dev/input/by-path: May 12 12:24:19 minerva volumio[4964]: platform-3f204000.spi-event May 12 12:24:19 minerva volumio[4964]: platform-rotary@10-event May 12 12:24:19 minerva systemd-udevd[5369]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] attachListener: /dev/input/by-path/platform-rotary@f-event May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] addEventHandle for rotary: 2 May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: 1,2,3 May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: 1,2 May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: 1 May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: end of recursion. May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: Now assign push button: 1, debounce: 10 May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] Push Button 1 now resolving. May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: Now assign push button: 2, debounce: 10 May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] Push Button 2 now resolving. May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] getI18nString("TOAST_SAVE_SUCCESS"):Speichern erfolgreich May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] getI18nString("TOAST_MSG_SAVE"):Einstellungen für Drehgeber May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] updateEncoder: SUCCESS with Toast: Speichern erfolgreich Einstellungen für Drehgeber 2 May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] getI18nString("TOAST_SAVE_SUCCESS"):Speichern erfolgreich May 12 12:24:20 minerva volumio[4964]: info: [ROTARYENCODER2] getI18nString("TOAST_MSG_SAVE"):Einstellungen für Drehgeber May 12 12:24:21 minerva sudo[5443]: volumio : TTY=pts/0 ; PWD=/var/log ; USER=root ; COMMAND=/bin/journalctl -f May 12 12:24:21 minerva sudo[5443]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) May 12 12:24:27 minerva volumio[4964]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] May 12 12:24:27 minerva volumio[4964]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] updateEncoder: Rotary2 with:{"enabled1":true,"rotaryType1":{"value":2,"label":"1/2"},"pinA1":15,"pinB1":23,"dialAction1":{"value":2,"label":"Titel vor/zurück"},"socketCmdCCW1":"","socketDataCCW1":"","socketCmdCW1":"","socketDataCW1":"","pinPush1":13,"pinPushDebounce1":10,"pushState1":true,"pushAction1":{"value":13,"label":"Websocket Nachricht senden"},"socketCmdPush1":"volume","socketDataPush1":"+","longPushAction1":{"value":0,"label":"..."},"socketCmdLongPush1":"listPlaylist","socketDataLongPush1":"","delayLongPush1":"1500","doublePushAction1":{"value":0,"label":"..."},"socketCmdDoublePush1":"","socketDataDoublePush1":"","delayDoublePush1":"700"} May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] sanityCheckSettings: Rotary2 for:{"enabled1":true,"rotaryType1":{"value":2,"label":"1/2"},"pinA1":15,"pinB1":23,"dialAction1":{"value":2,"label":"Titel vor/zurück"},"socketCmdCCW1":"","socketDataCCW1":"","socketCmdCW1":"","socketDataCW1":"","pinPush1":13,"pinPushDebounce1":10,"pushState1":true,"pushAction1":{"value":13,"label":"Websocket Nachricht senden"},"socketCmdPush1":"volume","socketDataPush1":"+","longPushAction1":{"value":0,"label":"..."},"socketCmdLongPush1":"listPlaylist","socketDataLongPush1":"","delayLongPush1":"1500","doublePushAction1":{"value":0,"label":"..."},"socketCmdDoublePush1":"","socketDataDoublePush1":"","delayDoublePush1":"700"} May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] sanityCheckSettings: allPins:16,12,6,15,23,13 May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: 1,2,3 May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: 2,3 May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: 3 May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: end of recursion. May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] detachListener: [object Object] May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] detachListener: successfully killed handler process May 12 12:24:27 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 2 May 12 12:24:28 minerva sudo[5448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 12 12:24:28 minerva sudo[5448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:28 minerva sudo[5448]: pam_unix(sudo:session): session closed for user root May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=15pinB=23 in Overlays (in load order): May 12 12:24:28 minerva volumio[4964]: 0: rotary-encoder pin_a=16 pin_b=12 relative_axis=true steps-per-period=2 May 12 12:24:28 minerva volumio[4964]: 1: rotary-encoder pin_a=15 pin_b=23 relative_axis=true steps-per-period=2 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: rotary 2uses overlay 1 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: 1 May 12 12:24:28 minerva sudo[5451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 1 May 12 12:24:28 minerva sudo[5451]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] addEventHandle: Stream from rotary encoder ended. May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] addEventHandle: child process exited with code null May 12 12:24:28 minerva sudo[5451]: pam_unix(sudo:session): session closed for user root May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: 1 returned: May 12 12:24:28 minerva sudo[5462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 12 12:24:28 minerva sudo[5462]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:28 minerva sudo[5462]: pam_unix(sudo:session): session closed for user root May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: "overlay -l" returned: Overlays (in load order): May 12 12:24:28 minerva volumio[4964]: 0: rotary-encoder pin_a=16 pin_b=12 relative_axis=true steps-per-period=2 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: deactivated rotary2 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] detachListener: [object Object] May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] detachListener: successfully killed handler process May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: Checking for existing overlays for Rotary: 1 May 12 12:24:28 minerva sudo[5465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 12 12:24:28 minerva sudo[5465]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:28 minerva sudo[5465]: pam_unix(sudo:session): session closed for user root May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: check pinA=16pinB=12 in Overlays (in load order): May 12 12:24:28 minerva volumio[4964]: 0: rotary-encoder pin_a=16 pin_b=12 relative_axis=true steps-per-period=2 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] checkOverlayExists: rotary 1uses overlay 0 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: 0 May 12 12:24:28 minerva sudo[5468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 12 12:24:28 minerva sudo[5468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] addEventHandle: Stream from rotary encoder ended. May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] addEventHandle: child process exited with code null May 12 12:24:28 minerva sudo[5468]: pam_unix(sudo:session): session closed for user root May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: 0 returned: May 12 12:24:28 minerva sudo[5477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 12 12:24:28 minerva sudo[5477]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:28 minerva sudo[5477]: pam_unix(sudo:session): session closed for user root May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] removeOverlay: "overlay -l" returned: No overlays loaded May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateRotaries: deactivated rotary1 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: 1,2,3 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: 2,3 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: 3 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: end of recursion. May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: button 3 is not configured. May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: deactivated button 2.([object Object]) May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] deactivateButtons: deactivated button 1.([object Object]) May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] updateEncoder: Changing Encoder 2 Settings to new values May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] activateRotaries: 1,2,3 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] activateRotaries: 1,2 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] activateRotaries: 1 May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] activateRotaries: May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] activateRotaries: end of recursion. May 12 12:24:28 minerva volumio[4964]: info: [ROTARYENCODER2] addOverlay: 16 12 2 May 12 12:24:28 minerva sudo[5480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=16 pin_b=12 relative_axis=true steps-per-period=2 May 12 12:24:28 minerva sudo[5480]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:28 minerva kernel: rotary-encoder rotary@10: gray May 12 12:24:28 minerva kernel: input: rotary@10 as /devices/platform/rotary@10/input/input11 May 12 12:24:28 minerva sudo[5480]: pam_unix(sudo:session): session closed for user root May 12 12:24:28 minerva volumio[4964]: info: /dev/input: May 12 12:24:28 minerva volumio[4964]: by-path May 12 12:24:28 minerva volumio[4964]: event0 May 12 12:24:28 minerva volumio[4964]: event1 May 12 12:24:28 minerva volumio[4964]: mice May 12 12:24:28 minerva volumio[4964]: mouse0 May 12 12:24:28 minerva volumio[4964]: /dev/input/by-path: May 12 12:24:28 minerva volumio[4964]: platform-3f204000.spi-event May 12 12:24:28 minerva systemd-udevd[5458]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 12 12:24:29 minerva volumio[4964]: info: [ROTARYENCODER2] attachListener: /dev/input/by-path/platform-rotary@10-event May 12 12:24:29 minerva volumio[4964]: info: [ROTARYENCODER2] addEventHandle for rotary: 1 May 12 12:24:29 minerva volumio[4964]: info: [ROTARYENCODER2] addOverlay: 15 23 2 May 12 12:24:29 minerva sudo[5496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=15 pin_b=23 relative_axis=true steps-per-period=2 May 12 12:24:29 minerva sudo[5496]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 12:24:29 minerva kernel: rotary-encoder rotary@f: gray May 12 12:24:29 minerva kernel: input: rotary@f as /devices/platform/rotary@f/input/input12 May 12 12:24:29 minerva sudo[5496]: pam_unix(sudo:session): session closed for user root May 12 12:24:29 minerva volumio[4964]: info: /dev/input: May 12 12:24:29 minerva volumio[4964]: by-path May 12 12:24:29 minerva volumio[4964]: event0 May 12 12:24:29 minerva volumio[4964]: event1 May 12 12:24:29 minerva volumio[4964]: event2 May 12 12:24:29 minerva volumio[4964]: mice May 12 12:24:29 minerva volumio[4964]: mouse0 May 12 12:24:29 minerva volumio[4964]: /dev/input/by-path: May 12 12:24:29 minerva volumio[4964]: platform-3f204000.spi-event May 12 12:24:29 minerva volumio[4964]: platform-rotary@10-event May 12 12:24:29 minerva systemd-udevd[5458]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] attachListener: /dev/input/by-path/platform-rotary@f-event May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] addEventHandle for rotary: 2 May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: 1,2,3 May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: 1,2 May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: 1 May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: end of recursion. May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: Now assign push button: 1, debounce: 10 May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] Push Button 1 now resolving. May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] activateButtons: Now assign push button: 2, debounce: 10 May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] Push Button 2 now resolving. May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] getI18nString("TOAST_SAVE_SUCCESS"):Speichern erfolgreich May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] getI18nString("TOAST_MSG_SAVE"):Einstellungen für Drehgeber May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] updateEncoder: SUCCESS with Toast: Speichern erfolgreich Einstellungen für Drehgeber 2 May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] getI18nString("TOAST_SAVE_SUCCESS"):Speichern erfolgreich May 12 12:24:30 minerva volumio[4964]: info: [ROTARYENCODER2] getI18nString("TOAST_MSG_SAVE"):Einstellungen für Drehgeber May 12 12:24:40 minerva volumio[4964]: info: [ROTARYENCODER2] Push Button 2 pressed. May 12 12:24:40 minerva volumio[4964]: info: [ROTARYENCODER2] Push Button 2 starting timers. May 12 12:24:40 minerva volumio[4964]: info: [ROTARYENCODER2] Push Button 2 released after 138ms. May 12 12:24:41 minerva volumio[4964]: info: [ROTARYENCODER2] Push Button 2 doublepush timer elapsed. (false, 1) May 12 12:24:41 minerva volumio[4964]: info: [ROTARYENCODER2] Push Button 2 sending single push command. May 12 12:24:41 minerva volumio[4964]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 12:24:41 minerva volumio[4964]: SyntaxError: Unexpected token + in JSON at position 0 May 12 12:24:41 minerva volumio[4964]: at JSON.parse () May 12 12:24:41 minerva volumio[4964]: at rotaryencoder2.emitPushCommand (/data/plugins/system_hardware/rotaryencoder2/index.js:743:17) May 12 12:24:41 minerva volumio[4964]: at Timeout._onTimeout (/data/plugins/system_hardware/rotaryencoder2/index.js:544:18) May 12 12:24:41 minerva volumio[4964]: at listOnTimeout (internal/timers.js:554:17) May 12 12:24:41 minerva volumio[4964]: at processTimers (internal/timers.js:497:7) May 12 12:24:41 minerva volumio[4964]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 12:24:41 minerva sudo[5540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 12:23 May 12 12:24:41 minerva sudo[5540]: 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"