-- Logs begin at Tue 2025-05-13 20:09:24 CEST, end at Tue 2025-05-13 20:37:00 CEST. -- May 13 20:36:00 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:00 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:00 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:00 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:00 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:00 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:15 volumio volumio[1100]: info: CALLMETHOD: system_controller system exec [object Object] May 13 20:36:15 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , exec May 13 20:36:15 volumio volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [exec] in plugin system May 13 20:36:15 volumio volumio[1100]: info: CALLMETHOD: system_controller system exec [object Object] May 13 20:36:15 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , exec May 13 20:36:15 volumio volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [exec] in plugin system May 13 20:36:15 volumio volumio[1100]: info: CALLMETHOD: system_controller system exec [object Object] May 13 20:36:15 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , exec May 13 20:36:15 volumio volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [exec] in plugin system May 13 20:36:15 volumio volumio[1100]: info: CALLMETHOD: system_controller system exec [object Object] May 13 20:36:15 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , exec May 13 20:36:15 volumio volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [exec] in plugin system May 13 20:36:15 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:15 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:15 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:15 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:15 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:15 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:15 volumio volumio[1100]: info: CALLMETHOD: system_controller system exec [object Object] May 13 20:36:15 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , exec May 13 20:36:15 volumio volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [exec] in plugin system May 13 20:36:15 volumio volumio[1100]: info: CALLMETHOD: system_controller system exec [object Object] May 13 20:36:15 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , exec May 13 20:36:15 volumio volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [exec] in plugin system May 13 20:36:15 volumio volumio[1100]: info: CALLMETHOD: system_controller system exec [object Object] May 13 20:36:15 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , exec May 13 20:36:15 volumio volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [exec] in plugin system May 13 20:36:16 volumio volumio[1100]: info: CALLMETHOD: system_controller system exec [object Object] May 13 20:36:16 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , exec May 13 20:36:16 volumio volumio[1100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [exec] in plugin system May 13 20:36:16 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:16 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:28 volumio go-librespot[1362]: time="2025-05-13T20:36:28+02:00" level=trace msg="sent dealer ping" May 13 20:36:28 volumio go-librespot[1362]: time="2025-05-13T20:36:28+02:00" level=trace msg="received dealer pong" May 13 20:36:34 volumio volumio[1100]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] May 13 20:36:34 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder May 13 20:36:34 volumio volumio[1100]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 13 20:36:34 volumio volumio[1100]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 13 20:36:34 volumio sudo[3613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:34 volumio sudo[3613]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:34 volumio sudo[3613]: pam_unix(sudo:session): session closed for user root May 13 20:36:34 volumio sudo[3616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 20:36:34 volumio sudo[3616]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:34 volumio kernel: input: button@4 as /devices/platform/button@4/input/input74 May 13 20:36:34 volumio sudo[3616]: pam_unix(sudo:session): session closed for user root May 13 20:36:34 volumio sudo[3631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:34 volumio sudo[3631]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:34 volumio sudo[3631]: pam_unix(sudo:session): session closed for user root May 13 20:36:34 volumio sudo[3636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:34 volumio sudo[3636]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:34 volumio sudo[3636]: pam_unix(sudo:session): session closed for user root May 13 20:36:34 volumio systemd-udevd[3621]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 20:36:34 volumio sudo[3639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 20:36:34 volumio sudo[3639]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:34 volumio startx[1365]: [dix] couldn't enable device 11 May 13 20:36:35 volumio sudo[3639]: pam_unix(sudo:session): session closed for user root May 13 20:36:35 volumio sudo[3648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:35 volumio sudo[3648]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:35 volumio sudo[3648]: pam_unix(sudo:session): session closed for user root May 13 20:36:35 volumio sudo[3651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=24 pin_b=27 relative_axis=true steps-per-period=1 May 13 20:36:35 volumio sudo[3651]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:35 volumio kernel: rotary-encoder rotary@18: gray May 13 20:36:35 volumio kernel: input: rotary@18 as /devices/platform/rotary@18/input/input75 May 13 20:36:35 volumio sudo[3651]: pam_unix(sudo:session): session closed for user root May 13 20:36:35 volumio systemd-udevd[3621]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 20:36:35 volumio volumio[1100]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] May 13 20:36:35 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder May 13 20:36:35 volumio volumio[1100]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 13 20:36:35 volumio volumio[1100]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 13 20:36:35 volumio volumio[1100]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 13 20:36:35 volumio volumio[1100]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 13 20:36:35 volumio sudo[3666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:35 volumio sudo[3666]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:35 volumio sudo[3666]: pam_unix(sudo:session): session closed for user root May 13 20:36:35 volumio sudo[3669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 20:36:35 volumio sudo[3669]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:35 volumio sudo[3669]: pam_unix(sudo:session): session closed for user root May 13 20:36:35 volumio sudo[3678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:35 volumio sudo[3678]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:35 volumio sudo[3678]: pam_unix(sudo:session): session closed for user root May 13 20:36:35 volumio sudo[3681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:35 volumio sudo[3681]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:35 volumio sudo[3681]: pam_unix(sudo:session): session closed for user root May 13 20:36:35 volumio sudo[3684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=24 pin_b=27 relative_axis=true steps-per-period=1 May 13 20:36:35 volumio sudo[3684]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:35 volumio kernel: rotary-encoder rotary@18: gray May 13 20:36:35 volumio kernel: input: rotary@18 as /devices/platform/rotary@18/input/input76 May 13 20:36:35 volumio sudo[3684]: pam_unix(sudo:session): session closed for user root May 13 20:36:35 volumio systemd-udevd[3621]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 20:36:36 volumio sudo[3697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=4 active_low=1 gpio_pull=up keycode=20 May 13 20:36:36 volumio sudo[3697]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:36 volumio kernel: input: button@4 as /devices/platform/button@4/input/input77 May 13 20:36:36 volumio sudo[3697]: pam_unix(sudo:session): session closed for user root May 13 20:36:36 volumio systemd-udevd[3621]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 20:36:36 volumio sudo[3711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=4 active_low=1 gpio_pull=up keycode=20 May 13 20:36:36 volumio sudo[3711]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:36 volumio sudo[3711]: pam_unix(sudo:session): session closed for user root May 13 20:36:36 volumio volumio[1100]: error: [ROTARYENCODER2] addOverlay: * Failed to apply overlay '2_gpio-key' (kernel) May 13 20:36:36 volumio sudo[3720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:36 volumio sudo[3720]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:36 volumio sudo[3720]: pam_unix(sudo:session): session closed for user root May 13 20:36:37 volumio sudo[3724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:37 volumio sudo[3724]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:37 volumio sudo[3724]: pam_unix(sudo:session): session closed for user root May 13 20:36:37 volumio sudo[3728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:37 volumio sudo[3728]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:37 volumio sudo[3728]: pam_unix(sudo:session): session closed for user root May 13 20:36:37 volumio sudo[3734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:37 volumio sudo[3734]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:37 volumio sudo[3734]: pam_unix(sudo:session): session closed for user root May 13 20:36:39 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 May 13 20:36:39 volumio volumio[1100]: info: CorePlayQueue::getTrack 1 May 13 20:36:39 volumio volumio[1100]: info: Prefetching next song May 13 20:36:39 volumio volumio[1100]: info: DOING PREFETCH IN MPD May 13 20:36:39 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Kruder & Dorfmeister/Kruder & Dorfmeister - 1995 - 02 - Love Hope Change.mp3" May 13 20:36:39 volumio volumio[1100]: info: May 13 20:36:39 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 13 20:36:39 volumio volumio[1100]: info: Ignoring MPD Status Update May 13 20:36:39 volumio volumio[1100]: info: sendMpdCommand add "INTERNAL/Kruder & Dorfmeister/Kruder & Dorfmeister - 1995 - 02 - Love Hope Change.mp3" took 1 milliseconds May 13 20:36:39 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand consume 1 May 13 20:36:39 volumio volumio[1100]: info: May 13 20:36:39 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 13 20:36:39 volumio volumio[1100]: info: Ignoring MPD Status Update May 13 20:36:39 volumio volumio[1100]: info: May 13 20:36:39 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 13 20:36:39 volumio volumio[1100]: info: Ignoring MPD Status Update May 13 20:36:39 volumio volumio[1100]: info: ------------------------------ 6ms May 13 20:36:39 volumio volumio[1100]: info: sendMpdCommand consume 1 took 6 milliseconds May 13 20:36:39 volumio volumio[1100]: info: ------------------------------ 5ms May 13 20:36:39 volumio volumio[1100]: info: ------------------------------ 2ms May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:40 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:41 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:42 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: error: Failed callmethod call: TypeError: Cannot read property 'substring' of undefined May 13 20:36:43 volumio volumio[1100]: info: May 13 20:36:43 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 13 20:36:43 volumio volumio[1100]: info: Ignoring MPD Status Update May 13 20:36:43 volumio volumio[1100]: info: May 13 20:36:43 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 13 20:36:43 volumio volumio[1100]: info: ControllerMpd::getState May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 13 20:36:43 volumio volumio[1100]: info: May 13 20:36:43 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 13 20:36:43 volumio volumio[1100]: info: Ignoring MPD Status Update May 13 20:36:43 volumio volumio[1100]: info: May 13 20:36:43 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 13 20:36:43 volumio volumio[1100]: info: ControllerMpd::getState May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 13 20:36:43 volumio volumio[1100]: info: May 13 20:36:43 volumio volumio[1100]: ---------------------------- MPD announces system playlist update May 13 20:36:43 volumio volumio[1100]: info: Ignoring MPD Status Update May 13 20:36:43 volumio volumio[1100]: info: May 13 20:36:43 volumio volumio[1100]: ---------------------------- MPD announces state update: player May 13 20:36:43 volumio volumio[1100]: info: ControllerMpd::getState May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand status May 13 20:36:43 volumio volumio[1100]: info: ------------------------------ 4ms May 13 20:36:43 volumio volumio[1100]: info: sendMpdCommand status took 4 milliseconds May 13 20:36:43 volumio volumio[1100]: info: ------------------------------ 3ms May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::parseState May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:36:43 volumio volumio[1100]: info: sendMpdCommand status took 6 milliseconds May 13 20:36:43 volumio volumio[1100]: info: ------------------------------ 6ms May 13 20:36:43 volumio volumio[1100]: info: sendMpdCommand status took 6 milliseconds May 13 20:36:43 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 4 milliseconds May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::parseState May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::parseState May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 13 20:36:43 volumio volumio[1100]: info: ControllerMpd::pushState May 13 20:36:43 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 13 20:36:43 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 May 13 20:36:43 volumio volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love Hope Change","artist":"Kruder & Dorfmeister","album":"1995","uri":"INTERNAL/Kruder & Dorfmeister/Kruder & Dorfmeister - 1995 - 02 - Love Hope Change.mp3","trackType":"mp3"} May 13 20:36:43 volumio volumio[1100]: verbose: CURRENT POSITION 0 May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::syncState stateService play May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 13 20:36:43 volumio volumio[1100]: info: Received an update from plugin. extracting info from payload May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::pushState May 13 20:36:43 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 May 13 20:36:43 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 20:36:43 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::pushState May 13 20:36:43 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 May 13 20:36:43 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 13 20:36:43 volumio volumio[1100]: info: ------------------------------ 21ms May 13 20:36:43 volumio volumio[1100]: info: sendMpdCommand playlistinfo took 13 milliseconds May 13 20:36:43 volumio volumio[1100]: verbose: ControllerMpd::parseTrackInfo May 13 20:36:43 volumio volumio[1100]: info: ControllerMpd::pushState May 13 20:36:43 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 13 20:36:43 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 May 13 20:36:43 volumio volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love Hope Change","artist":"Kruder & Dorfmeister","album":"1995","uri":"INTERNAL/Kruder & Dorfmeister/Kruder & Dorfmeister - 1995 - 02 - Love Hope Change.mp3","trackType":"mp3"} May 13 20:36:43 volumio volumio[1100]: verbose: CURRENT POSITION 0 May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::syncState stateService play May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 13 20:36:43 volumio volumio[1100]: info: Received an update from plugin. extracting info from payload May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::pushState May 13 20:36:43 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 May 13 20:36:43 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::pushState May 13 20:36:43 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 May 13 20:36:43 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 13 20:36:43 volumio volumio[1100]: info: ControllerMpd::pushState May 13 20:36:43 volumio volumio[1100]: info: CoreCommandRouter::servicePushState May 13 20:36:43 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 May 13 20:36:43 volumio volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Love Hope Change","artist":"Kruder & Dorfmeister","album":"1995","uri":"INTERNAL/Kruder & Dorfmeister/Kruder & Dorfmeister - 1995 - 02 - Love Hope Change.mp3","trackType":"mp3"} May 13 20:36:43 volumio volumio[1100]: verbose: CURRENT POSITION 0 May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::syncState stateService play May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::syncState currentStatus play May 13 20:36:43 volumio volumio[1100]: info: Received an update from plugin. extracting info from payload May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::pushState May 13 20:36:43 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 May 13 20:36:43 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 13 20:36:43 volumio volumio[1100]: info: CoreStateMachine::pushState May 13 20:36:43 volumio volumio[1100]: info: CorePlayQueue::getTrack 0 May 13 20:36:43 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 13 20:36:43 volumio volumio[1100]: info: ------------------------------ 43ms May 13 20:36:43 volumio volumio[1100]: info: ------------------------------ 43ms May 13 20:36:43 volumio volumio[1100]: info: [GPIO_Control] Status changed: play May 13 20:36:43 volumio volumio[1100]: info: [GPIO_Control] Status changed: play May 13 20:36:43 volumio volumio[1100]: info: [GPIO_Control] Status changed: play May 13 20:36:43 volumio volumio[1100]: info: [GPIO_Control] Status changed: play May 13 20:36:43 volumio volumio[1100]: info: [GPIO_Control] Status changed: play May 13 20:36:43 volumio volumio[1100]: info: [GPIO_Control] Status changed: play May 13 20:36:43 volumio volumio[1100]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: SPOTIFY VOLUME 52 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: DELTA VOLUME ENOUGH: false May 13 20:36:43 volumio volumio[1100]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: SPOTIFY VOLUME 52 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: DELTA VOLUME ENOUGH: false May 13 20:36:43 volumio volumio[1100]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: SPOTIFY VOLUME 52 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: DELTA VOLUME ENOUGH: false May 13 20:36:43 volumio volumio[1100]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: SPOTIFY VOLUME 52 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: DELTA VOLUME ENOUGH: false May 13 20:36:43 volumio volumio[1100]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: SPOTIFY VOLUME 52 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: DELTA VOLUME ENOUGH: false May 13 20:36:43 volumio volumio[1100]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: SPOTIFY VOLUME 52 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: VOLUMIO VOLUME 53 May 13 20:36:43 volumio volumio[1100]: SPOTIFY: DELTA VOLUME ENOUGH: false May 13 20:36:44 volumio volumio[1100]: info: CoreStateMachine::startPlaybackTimer May 13 20:36:44 volumio volumio[1100]: info: CorePlayQueue::getTrack 1 May 13 20:36:44 volumio volumio[1100]: info: CoreStateMachine::pushState May 13 20:36:44 volumio volumio[1100]: info: CorePlayQueue::getTrack 1 May 13 20:36:44 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 20:36:44 volumio volumio[1100]: info: CoreCommandRouter::volumioPushState May 13 20:36:44 volumio volumio[1100]: info: [GPIO_Control] Status changed: play May 13 20:36:44 volumio volumio[1100]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 13 20:36:44 volumio volumio[1100]: SPOTIFY: SPOTIFY VOLUME 52 May 13 20:36:44 volumio volumio[1100]: SPOTIFY: VOLUMIO VOLUME 53 May 13 20:36:44 volumio volumio[1100]: SPOTIFY: DELTA VOLUME ENOUGH: false May 13 20:36:57 volumio volumio[1100]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] May 13 20:36:57 volumio volumio[1100]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder May 13 20:36:57 volumio volumio[1100]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 13 20:36:57 volumio volumio[1100]: error: [ROTARYENCODER2] detachListener: could not kill handler process May 13 20:36:57 volumio sudo[3812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:57 volumio sudo[3812]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:57 volumio sudo[3812]: pam_unix(sudo:session): session closed for user root May 13 20:36:57 volumio sudo[3815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 20:36:57 volumio sudo[3815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:57 volumio volumio[1100]: error: [ROTARYENCODER2] addEventHandle: stderr: /bin/cat: /dev/input/by-path/platform-button@4-event: No such device May 13 20:36:57 volumio volumio[1100]: error: [ROTARYENCODER2] addEventHandle: stderr: /bin/cat: /dev/input/by-path/platform-rotary@18-event: No such device May 13 20:36:57 volumio kernel: input: button@4 as /devices/platform/button@4/input/input78 May 13 20:36:57 volumio go-librespot[1362]: time="2025-05-13T20:36:57+02:00" level=trace msg="received accesspoint ping" May 13 20:36:57 volumio sudo[3815]: pam_unix(sudo:session): session closed for user root May 13 20:36:57 volumio go-librespot[1362]: time="2025-05-13T20:36:57+02:00" level=trace msg="received accesspoint pong ack" May 13 20:36:57 volumio sudo[3830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:57 volumio sudo[3830]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:57 volumio sudo[3830]: pam_unix(sudo:session): session closed for user root May 13 20:36:57 volumio sudo[3835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:57 volumio sudo[3835]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:57 volumio sudo[3835]: pam_unix(sudo:session): session closed for user root May 13 20:36:57 volumio systemd-udevd[3820]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 20:36:57 volumio sudo[3838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 20:36:57 volumio sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:57 volumio sudo[3838]: pam_unix(sudo:session): session closed for user root May 13 20:36:58 volumio sudo[3848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:36:58 volumio sudo[3848]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:58 volumio sudo[3848]: pam_unix(sudo:session): session closed for user root May 13 20:36:58 volumio sudo[3851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=24 pin_b=27 relative_axis=true steps-per-period=1 May 13 20:36:58 volumio sudo[3851]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:58 volumio kernel: rotary-encoder rotary@18: gray May 13 20:36:58 volumio kernel: input: rotary@18 as /devices/platform/rotary@18/input/input79 May 13 20:36:58 volumio sudo[3851]: pam_unix(sudo:session): session closed for user root May 13 20:36:58 volumio go-librespot[1362]: time="2025-05-13T20:36:58+02:00" level=trace msg="sent dealer ping" May 13 20:36:58 volumio go-librespot[1362]: time="2025-05-13T20:36:58+02:00" level=trace msg="received dealer pong" May 13 20:36:58 volumio systemd-udevd[3820]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 20:36:59 volumio sudo[3866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=4 active_low=1 gpio_pull=up keycode=20 May 13 20:36:59 volumio sudo[3866]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:36:59 volumio kernel: input: button@4 as /devices/platform/button@4/input/input80 May 13 20:36:59 volumio sudo[3866]: pam_unix(sudo:session): session closed for user root May 13 20:36:59 volumio systemd-udevd[3820]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 20:37:00 volumio sudo[3880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:37:00 volumio sudo[3880]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:37:00 volumio sudo[3880]: pam_unix(sudo:session): session closed for user root May 13 20:37:00 volumio sudo[3884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 20:37:00 volumio sudo[3884]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 20:37:00 volumio sudo[3884]: pam_unix(sudo:session): session closed for user root May 13 20:37:00 volumio volumio[1100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 20:37:00 volumio volumio[1100]: SyntaxError: Unexpected token e in JSON at position 1 May 13 20:37:00 volumio volumio[1100]: at JSON.parse () May 13 20:37:00 volumio volumio[1100]: at rotaryencoder2.emitDialCommand (/data/plugins/system_hardware/rotaryencoder2/index.js:1010:73) May 13 20:37:00 volumio volumio[1100]: at Socket. (/data/plugins/system_hardware/rotaryencoder2/index.js:849:11) May 13 20:37:00 volumio volumio[1100]: at Socket.emit (events.js:315:20) May 13 20:37:00 volumio volumio[1100]: at addChunk (internal/streams/readable.js:309:12) May 13 20:37:00 volumio volumio[1100]: at readableAddChunk (internal/streams/readable.js:284:9) May 13 20:37:00 volumio volumio[1100]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 13 20:37:00 volumio volumio[1100]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 13 20:37:00 volumio volumio[1100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 20:37:00 volumio sudo[3896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 20:36 May 13 20:37:00 volumio sudo[3896]: 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"