-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-05-10 19:00:31 CEST. -- May 10 18:59:02 volumio go-librespot[7650]: time="2025-05-10T18:59:02+02:00" level=debug msg="fetched chunk 4/12, size: 524288" uri="spotify:track:3uLSUZEmTY50H6Kw17lpfW" May 10 18:59:07 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 18:59:07 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 18:59:07 volumio volumio[4395]: info: Discovery: Getting this device information May 10 18:59:07 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:07 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 18:59:07 volumio volumio[4395]: verbose: New Socket.io Connection to 192.168.178.103:3000 from 192.168.178.87 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 May 10 18:59:07 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:07 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 10 18:59:07 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 10 18:59:08 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 18:59:08 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 18:59:08 volumio volumio[4395]: info: Discovery: Getting this device information May 10 18:59:08 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:08 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 18:59:09 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:10 volumio sudo[12553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 18:59:10 volumio sudo[12553]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:10 volumio sudo[12553]: pam_unix(sudo:session): session closed for user root May 10 18:59:10 volumio sudo[12557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 18:59:10 volumio sudo[12557]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:10 volumio sudo[12557]: pam_unix(sudo:session): session closed for user root May 10 18:59:11 volumio volumio[4395]: verbose: New Socket.io Connection to 192.168.178.103 from 192.168.178.87 UA: Mozilla/5.0 (Linux; Android 15; SM-S931B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.113 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::volumioGetVisibleSources May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 10 18:59:11 volumio volumio[4395]: info: Received Get System Info May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 18:59:11 volumio volumio[4395]: info: Discovery: Getting this device information May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:11 volumio volumio[4395]: info: Listing playlists May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 10 18:59:11 volumio sudo[12563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 18:59:11 volumio sudo[12563]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:11 volumio sudo[12563]: pam_unix(sudo:session): session closed for user root May 10 18:59:11 volumio sudo[12566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 18:59:11 volumio sudo[12566]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:11 volumio sudo[12566]: pam_unix(sudo:session): session closed for user root May 10 18:59:11 volumio volumio[4395]: verbose: New Socket.io Connection to 192.168.178.103 from 192.168.178.87 UA: Mozilla/5.0 (Linux; Android 15; SM-S931B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.113 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::volumioGetVisibleSources May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 10 18:59:11 volumio volumio[4395]: info: Received Get System Info May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 18:59:11 volumio volumio[4395]: info: Discovery: Getting this device information May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:11 volumio volumio[4395]: info: Listing playlists May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 10 18:59:11 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 10 18:59:13 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 10 18:59:13 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 10 18:59:13 volumio volumio[4395]: info: Received Get System Info May 10 18:59:13 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 18:59:13 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 18:59:13 volumio volumio[4395]: info: Discovery: Getting this device information May 10 18:59:13 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:13 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 18:59:13 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 10 18:59:14 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 10 18:59:14 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 10 18:59:15 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 10 18:59:15 volumio volumio[4395]: info: Received Get System Info May 10 18:59:15 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 18:59:15 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 18:59:15 volumio volumio[4395]: info: Discovery: Getting this device information May 10 18:59:15 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:15 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 18:59:16 volumio go-librespot[7650]: time="2025-05-10T18:59:16+02:00" level=debug msg="fetched chunk 5/12, size: 524288" uri="spotify:track:3uLSUZEmTY50H6Kw17lpfW" May 10 18:59:16 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 10 18:59:16 volumio volumio[4395]: info: Received Get System Info May 10 18:59:16 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 18:59:16 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 18:59:16 volumio volumio[4395]: info: Discovery: Getting this device information May 10 18:59:16 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:16 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 18:59:16 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 10 18:59:16 volumio volumio[4395]: info: Received Get System Info May 10 18:59:16 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 18:59:16 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 18:59:16 volumio volumio[4395]: info: Discovery: Getting this device information May 10 18:59:16 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:16 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 18:59:18 volumio volumio[4395]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 10 18:59:18 volumio volumio[4395]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 10 18:59:18 volumio volumio[4395]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 10 18:59:18 volumio sudo[12583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:18 volumio sudo[12583]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:18 volumio sudo[12583]: pam_unix(sudo:session): session closed for user root May 10 18:59:18 volumio sudo[12586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 2 May 10 18:59:18 volumio sudo[12586]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:18 volumio kernel: gpio-keys button@18: error -EBUSY: failed to get gpio May 10 18:59:19 volumio kernel: gpio-keys: probe of button@18 failed with error -16 May 10 18:59:19 volumio sudo[12586]: pam_unix(sudo:session): session closed for user root May 10 18:59:19 volumio sudo[12599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:19 volumio sudo[12599]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:19 volumio sudo[12599]: pam_unix(sudo:session): session closed for user root May 10 18:59:19 volumio sudo[12602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:19 volumio sudo[12602]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:19 volumio sudo[12602]: pam_unix(sudo:session): session closed for user root May 10 18:59:19 volumio sudo[12605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 2 May 10 18:59:19 volumio sudo[12605]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:19 volumio sudo[12605]: pam_unix(sudo:session): session closed for user root May 10 18:59:19 volumio sudo[12615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:19 volumio sudo[12615]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:19 volumio sudo[12615]: pam_unix(sudo:session): session closed for user root May 10 18:59:19 volumio sudo[12618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:19 volumio sudo[12618]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:19 volumio sudo[12618]: pam_unix(sudo:session): session closed for user root May 10 18:59:19 volumio sudo[12621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 10 18:59:19 volumio sudo[12621]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:19 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin gpio7 already requested by 3f204000.spi; cannot claim for button@7 May 10 18:59:19 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin-7 (button@7) status -22 May 10 18:59:19 volumio kernel: pinctrl-bcm2835 3f200000.gpio: could not request pin 7 (gpio7) from group gpio7 on device pinctrl-bcm2835 May 10 18:59:19 volumio kernel: gpio-keys button@7: Error applying setting, reverse things back May 10 18:59:19 volumio sudo[12621]: pam_unix(sudo:session): session closed for user root May 10 18:59:19 volumio sudo[12631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:19 volumio sudo[12631]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:19 volumio sudo[12631]: pam_unix(sudo:session): session closed for user root May 10 18:59:19 volumio sudo[12634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:19 volumio sudo[12634]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:19 volumio sudo[12634]: pam_unix(sudo:session): session closed for user root May 10 18:59:19 volumio sudo[12637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 10 18:59:19 volumio sudo[12637]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:20 volumio sudo[12637]: pam_unix(sudo:session): session closed for user root May 10 18:59:20 volumio sudo[12646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:20 volumio sudo[12646]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:20 volumio sudo[12646]: pam_unix(sudo:session): session closed for user root May 10 18:59:20 volumio volumio[4395]: info: Disabling plugin rotaryencoder2 May 10 18:59:20 volumio volumio[4395]: info: Done. May 10 18:59:21 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 10 18:59:22 volumio volumio[4395]: info: Enabling plugin rotaryencoder2 May 10 18:59:22 volumio volumio[4395]: info: Loading plugin "rotaryencoder2"... May 10 18:59:22 volumio volumio[4395]: info: PLUGIN START: rotaryencoder2 May 10 18:59:22 volumio sudo[12649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=16 pin_b=12 relative_axis=true steps-per-period=1 May 10 18:59:22 volumio sudo[12649]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:22 volumio volumio[4395]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 May 10 18:59:22 volumio kernel: rotary-encoder rotary@10: gray May 10 18:59:22 volumio kernel: input: rotary@10 as /devices/platform/rotary@10/input/input3 May 10 18:59:22 volumio sudo[12649]: pam_unix(sudo:session): session closed for user root May 10 18:59:22 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:22 volumio systemd-udevd[12592]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 10 18:59:23 volumio sudo[12662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=7 active_low=1 gpio_pull=up keycode=20 May 10 18:59:23 volumio sudo[12662]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:23 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin gpio7 already requested by 3f204000.spi; cannot claim for button@7 May 10 18:59:23 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin-7 (button@7) status -22 May 10 18:59:23 volumio kernel: pinctrl-bcm2835 3f200000.gpio: could not request pin 7 (gpio7) from group gpio7 on device pinctrl-bcm2835 May 10 18:59:23 volumio kernel: gpio-keys button@7: Error applying setting, reverse things back May 10 18:59:23 volumio sudo[12662]: pam_unix(sudo:session): session closed for user root May 10 18:59:24 volumio sudo[12671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=8 pin_b=25 relative_axis=true steps-per-period=1 May 10 18:59:24 volumio sudo[12671]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:24 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin gpio8 already requested by 3f204000.spi; cannot claim for rotary@8 May 10 18:59:25 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin-8 (rotary@8) status -22 May 10 18:59:25 volumio kernel: pinctrl-bcm2835 3f200000.gpio: could not request pin 8 (gpio8) from group gpio8 on device pinctrl-bcm2835 May 10 18:59:25 volumio kernel: rotary-encoder rotary@8: Error applying setting, reverse things back May 10 18:59:25 volumio sudo[12671]: pam_unix(sudo:session): session closed for user root May 10 18:59:26 volumio sudo[12694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=24 active_low=1 gpio_pull=up keycode=20 May 10 18:59:26 volumio sudo[12694]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:26 volumio kernel: gpio-keys button@18: error -EBUSY: failed to get gpio May 10 18:59:26 volumio kernel: gpio-keys: probe of button@18 failed with error -16 May 10 18:59:26 volumio sudo[12694]: pam_unix(sudo:session): session closed for user root May 10 18:59:26 volumio go-librespot[7650]: time="2025-05-10T18:59:26+02:00" level=trace msg="sent dealer ping" May 10 18:59:26 volumio go-librespot[7650]: time="2025-05-10T18:59:26+02:00" level=trace msg="received dealer pong" May 10 18:59:27 volumio sudo[12703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:27 volumio sudo[12703]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:27 volumio sudo[12703]: pam_unix(sudo:session): session closed for user root May 10 18:59:27 volumio sudo[12707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:27 volumio sudo[12707]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:27 volumio sudo[12707]: pam_unix(sudo:session): session closed for user root May 10 18:59:27 volumio sudo[12711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:27 volumio sudo[12711]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:27 volumio sudo[12711]: pam_unix(sudo:session): session closed for user root May 10 18:59:27 volumio sudo[12715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:27 volumio sudo[12715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:27 volumio sudo[12715]: pam_unix(sudo:session): session closed for user root May 10 18:59:27 volumio volumio[4395]: info: Done. May 10 18:59:27 volumio volumio[4395]: error: [ROTARYENCODER2] addEventHandle: stderr: /bin/cat: /dev/input/by-path/platform-button@18-event: No such file or directory May 10 18:59:29 volumio go-librespot[7650]: time="2025-05-10T18:59:29+02:00" level=debug msg="fetched chunk 6/12, size: 524288" uri="spotify:track:3uLSUZEmTY50H6Kw17lpfW" May 10 18:59:31 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 10 18:59:31 volumio volumio[4395]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 10 18:59:31 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 10 18:59:31 volumio volumio[4395]: info: Received Get System Version May 10 18:59:31 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 18:59:31 volumio volumio[4395]: info: Received Get System Info May 10 18:59:31 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 18:59:31 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 18:59:31 volumio volumio[4395]: info: Discovery: Getting this device information May 10 18:59:31 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:31 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 18:59:35 volumio volumio[4395]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 18:59:36 volumio volumio[4395]: info: CorePlayQueue::getTrack 9 May 10 18:59:36 volumio volumio[4395]: info: CorePlayQueue::getTrack 10 May 10 18:59:36 volumio volumio[4395]: info: Prefetching next song May 10 18:59:36 volumio volumio[4395]: info: [1746896376479] ControllerSpotify::prefetch May 10 18:59:36 volumio volumio[4395]: info: Sending Spotify command with payload to local API: /player/add_to_queue May 10 18:59:36 volumio go-librespot[7650]: time="2025-05-10T18:59:36+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 10 18:59:36 volumio go-librespot[7650]: time="2025-05-10T18:59:36+02:00" level=trace msg="scheduling prefetch in 92s" May 10 18:59:37 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 18:59:37 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 18:59:37 volumio volumio[4395]: info: Discovery: Getting this device information May 10 18:59:37 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 18:59:37 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 18:59:40 volumio volumio[4395]: info: CoreStateMachine::startPlaybackTimer May 10 18:59:40 volumio volumio[4395]: info: CorePlayQueue::getTrack 10 May 10 18:59:41 volumio volumio[4395]: info: CoreStateMachine::pushState May 10 18:59:41 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 18:59:41 volumio volumio[4395]: info: CoreCommandRouter::volumioPushState May 10 18:59:41 volumio volumio[4395]: info: [ASDebug] CurState: play PrevState: play May 10 18:59:41 volumio volumio[4395]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 May 10 18:59:42 volumio go-librespot[7650]: time="2025-05-10T18:59:42+02:00" level=debug msg="fetched chunk 7/12, size: 524288" uri="spotify:track:3uLSUZEmTY50H6Kw17lpfW" May 10 18:59:45 volumio volumio[4395]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] May 10 18:59:45 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder May 10 18:59:45 volumio volumio[4395]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 10 18:59:45 volumio volumio[4395]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 10 18:59:45 volumio volumio[4395]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 10 18:59:45 volumio sudo[12783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:45 volumio sudo[12783]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:45 volumio sudo[12783]: pam_unix(sudo:session): session closed for user root May 10 18:59:45 volumio sudo[12786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 2 May 10 18:59:45 volumio sudo[12786]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:45 volumio kernel: gpio-keys button@18: error -EBUSY: failed to get gpio May 10 18:59:45 volumio kernel: gpio-keys: probe of button@18 failed with error -16 May 10 18:59:45 volumio sudo[12786]: pam_unix(sudo:session): session closed for user root May 10 18:59:45 volumio sudo[12797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:45 volumio sudo[12797]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:45 volumio sudo[12797]: pam_unix(sudo:session): session closed for user root May 10 18:59:45 volumio sudo[12800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:45 volumio sudo[12800]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:45 volumio sudo[12800]: pam_unix(sudo:session): session closed for user root May 10 18:59:45 volumio sudo[12803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 2 May 10 18:59:45 volumio sudo[12803]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:45 volumio sudo[12803]: pam_unix(sudo:session): session closed for user root May 10 18:59:45 volumio sudo[12812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:45 volumio sudo[12812]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:45 volumio sudo[12812]: pam_unix(sudo:session): session closed for user root May 10 18:59:45 volumio sudo[12815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:45 volumio sudo[12815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:45 volumio sudo[12815]: pam_unix(sudo:session): session closed for user root May 10 18:59:45 volumio sudo[12826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 10 18:59:45 volumio sudo[12826]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:45 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin gpio7 already requested by 3f204000.spi; cannot claim for button@7 May 10 18:59:45 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin-7 (button@7) status -22 May 10 18:59:45 volumio kernel: pinctrl-bcm2835 3f200000.gpio: could not request pin 7 (gpio7) from group gpio7 on device pinctrl-bcm2835 May 10 18:59:45 volumio kernel: gpio-keys button@7: Error applying setting, reverse things back May 10 18:59:45 volumio sudo[12826]: pam_unix(sudo:session): session closed for user root May 10 18:59:46 volumio sudo[12842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:46 volumio sudo[12842]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:46 volumio sudo[12842]: pam_unix(sudo:session): session closed for user root May 10 18:59:46 volumio sudo[12845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:46 volumio sudo[12845]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:46 volumio sudo[12845]: pam_unix(sudo:session): session closed for user root May 10 18:59:46 volumio sudo[12848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 10 18:59:46 volumio sudo[12848]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:46 volumio sudo[12848]: pam_unix(sudo:session): session closed for user root May 10 18:59:46 volumio sudo[12858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:46 volumio sudo[12858]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:46 volumio sudo[12858]: pam_unix(sudo:session): session closed for user root May 10 18:59:46 volumio sudo[12861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=16 pin_b=12 relative_axis=true steps-per-period=1 May 10 18:59:46 volumio sudo[12861]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:46 volumio kernel: rotary-encoder rotary@10: gray May 10 18:59:46 volumio kernel: input: rotary@10 as /devices/platform/rotary@10/input/input6 May 10 18:59:46 volumio sudo[12861]: pam_unix(sudo:session): session closed for user root May 10 18:59:46 volumio systemd-udevd[12837]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 10 18:59:47 volumio sudo[12874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=7 active_low=1 gpio_pull=up keycode=20 May 10 18:59:47 volumio sudo[12874]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:47 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin gpio7 already requested by 3f204000.spi; cannot claim for button@7 May 10 18:59:47 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin-7 (button@7) status -22 May 10 18:59:47 volumio kernel: pinctrl-bcm2835 3f200000.gpio: could not request pin 7 (gpio7) from group gpio7 on device pinctrl-bcm2835 May 10 18:59:47 volumio kernel: gpio-keys button@7: Error applying setting, reverse things back May 10 18:59:47 volumio sudo[12874]: pam_unix(sudo:session): session closed for user root May 10 18:59:48 volumio sudo[12883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=8 pin_b=25 relative_axis=true steps-per-period=1 May 10 18:59:48 volumio sudo[12883]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:48 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin gpio8 already requested by 3f204000.spi; cannot claim for rotary@8 May 10 18:59:48 volumio kernel: pinctrl-bcm2835 3f200000.gpio: pin-8 (rotary@8) status -22 May 10 18:59:48 volumio kernel: pinctrl-bcm2835 3f200000.gpio: could not request pin 8 (gpio8) from group gpio8 on device pinctrl-bcm2835 May 10 18:59:48 volumio kernel: rotary-encoder rotary@8: Error applying setting, reverse things back May 10 18:59:48 volumio sudo[12883]: pam_unix(sudo:session): session closed for user root May 10 18:59:49 volumio sudo[12892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=24 active_low=1 gpio_pull=up keycode=20 May 10 18:59:49 volumio sudo[12892]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:49 volumio kernel: gpio-keys button@18: error -EBUSY: failed to get gpio May 10 18:59:49 volumio kernel: gpio-keys: probe of button@18 failed with error -16 May 10 18:59:49 volumio sudo[12892]: pam_unix(sudo:session): session closed for user root May 10 18:59:50 volumio sudo[12901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:50 volumio sudo[12901]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:51 volumio sudo[12901]: pam_unix(sudo:session): session closed for user root May 10 18:59:51 volumio sudo[12905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:51 volumio sudo[12905]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:51 volumio sudo[12905]: pam_unix(sudo:session): session closed for user root May 10 18:59:51 volumio sudo[12912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:51 volumio sudo[12912]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:51 volumio sudo[12912]: pam_unix(sudo:session): session closed for user root May 10 18:59:51 volumio sudo[12916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 10 18:59:51 volumio sudo[12916]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 18:59:51 volumio sudo[12916]: pam_unix(sudo:session): session closed for user root May 10 18:59:51 volumio volumio[4395]: error: [ROTARYENCODER2] addEventHandle: stderr: /bin/cat: /dev/input/by-path/platform-button@18-event: No such file or directory May 10 18:59:52 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 10 18:59:52 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 10 18:59:54 volumio go-librespot[7650]: time="2025-05-10T18:59:54+02:00" level=debug msg="fetched chunk 8/12, size: 524288" uri="spotify:track:3uLSUZEmTY50H6Kw17lpfW" May 10 18:59:56 volumio go-librespot[7650]: time="2025-05-10T18:59:56+02:00" level=trace msg="received accesspoint ping" May 10 18:59:56 volumio go-librespot[7650]: time="2025-05-10T18:59:56+02:00" level=trace msg="received accesspoint pong ack" May 10 18:59:56 volumio go-librespot[7650]: time="2025-05-10T18:59:56+02:00" level=trace msg="sent dealer ping" May 10 18:59:56 volumio go-librespot[7650]: time="2025-05-10T18:59:56+02:00" level=trace msg="received dealer pong" May 10 19:00:00 volumio volumio[4395]: info: Starting Uninstall of plugin system_controller - ampswitch May 10 19:00:00 volumio volumio[4395]: info: Uninstalling plugin ampswitch May 10 19:00:00 volumio volumio[4395]: info: [ASDebug] Port: 24 May 10 19:00:00 volumio volumio[4395]: info: [ASDebug] Inverted: false May 10 19:00:00 volumio volumio[4395]: info: [ASDebug] Delay: 720 May 10 19:00:07 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 19:00:07 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 19:00:07 volumio volumio[4395]: info: Discovery: Getting this device information May 10 19:00:07 volumio volumio[4395]: info: CoreCommandRouter::volumioGetState May 10 19:00:07 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 19:00:07 volumio go-librespot[7650]: time="2025-05-10T19:00:07+02:00" level=debug msg="fetched chunk 9/12, size: 524288" uri="spotify:track:3uLSUZEmTY50H6Kw17lpfW" May 10 19:00:08 volumio volumio[4395]: info: Starting Uninstall of plugin system_controller - ampswitch May 10 19:00:08 volumio volumio[4395]: info: Uninstalling plugin ampswitch May 10 19:00:08 volumio volumio[4395]: info: [ASDebug] Port: 24 May 10 19:00:08 volumio volumio[4395]: info: [ASDebug] Inverted: false May 10 19:00:08 volumio volumio[4395]: info: [ASDebug] Delay: 720 May 10 19:00:15 volumio kernel: hwmon hwmon1: Voltage normalised May 10 19:00:15 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 10 19:00:15 volumio volumio[4395]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 10 19:00:17 volumio kernel: hwmon hwmon1: Undervoltage detected! May 10 19:00:21 volumio go-librespot[7650]: time="2025-05-10T19:00:21+02:00" level=debug msg="fetched chunk 10/12, size: 524288" uri="spotify:track:3uLSUZEmTY50H6Kw17lpfW" May 10 19:00:26 volumio go-librespot[7650]: time="2025-05-10T19:00:26+02:00" level=trace msg="sent dealer ping" May 10 19:00:26 volumio go-librespot[7650]: time="2025-05-10T19:00:26+02:00" level=trace msg="received dealer pong" May 10 19:00:30 volumio volumio[4395]: info: Starting Uninstall of plugin system_controller - ampswitch May 10 19:00:30 volumio volumio[4395]: info: Uninstalling plugin ampswitch May 10 19:00:30 volumio volumio[4395]: info: [ASDebug] Port: 24 May 10 19:00:30 volumio volumio[4395]: info: [ASDebug] Inverted: false May 10 19:00:30 volumio volumio[4395]: info: [ASDebug] Delay: 720 May 10 19:00:30 volumio volumio[4395]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 19:00:30 volumio volumio[4395]: Error: EBADF: bad file descriptor, close May 10 19:00:30 volumio volumio[4395]: at Object.closeSync (fs.js:439:3) May 10 19:00:30 volumio volumio[4395]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) May 10 19:00:30 volumio volumio[4395]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) May 10 19:00:30 volumio volumio[4395]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) May 10 19:00:30 volumio volumio[4395]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) May 10 19:00:30 volumio volumio[4395]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) May 10 19:00:30 volumio volumio[4395]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 10 19:00:30 volumio volumio[4395]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) May 10 19:00:30 volumio volumio[4395]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) May 10 19:00:30 volumio volumio[4395]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) May 10 19:00:30 volumio volumio[4395]: at Socket.emit (events.js:315:20) May 10 19:00:30 volumio volumio[4395]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 10 19:00:30 volumio volumio[4395]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 10 19:00:30 volumio volumio[4395]: errno: -9, May 10 19:00:30 volumio volumio[4395]: syscall: 'close', May 10 19:00:30 volumio volumio[4395]: code: 'EBADF' May 10 19:00:30 volumio volumio[4395]: } May 10 19:00:30 volumio volumio[4395]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 19:00:31 volumio sudo[12995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-10 18:59 May 10 19:00:31 volumio sudo[12995]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"