-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2025-05-13 23:44:40 CEST. -- May 13 23:43:04 volumio volumio[1079]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] May 13 23:43:04 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder May 13 23:43:04 volumio sudo[2578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:04 volumio sudo[2578]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:04 volumio sudo[2578]: pam_unix(sudo:session): session closed for user root May 13 23:43:04 volumio sudo[2581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 23:43:04 volumio sudo[2581]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:04 volumio kernel: input: button@4 as /devices/platform/button@4/input/input16 May 13 23:43:04 volumio sudo[2581]: pam_unix(sudo:session): session closed for user root May 13 23:43:04 volumio sudo[2595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:04 volumio sudo[2595]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:04 volumio sudo[2595]: pam_unix(sudo:session): session closed for user root May 13 23:43:04 volumio sudo[2599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:04 volumio sudo[2599]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:04 volumio sudo[2599]: pam_unix(sudo:session): session closed for user root May 13 23:43:04 volumio sudo[2603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 23:43:04 volumio sudo[2603]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:04 volumio systemd-udevd[2586]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:43:04 volumio systemd-logind[662]: Failed to open /dev/input/event5: No such device May 13 23:43:04 volumio sudo[2603]: pam_unix(sudo:session): session closed for user root May 13 23:43:04 volumio sudo[2612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:04 volumio sudo[2612]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:04 volumio sudo[2612]: pam_unix(sudo:session): session closed for user root May 13 23:43:04 volumio sudo[2615]: 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 23:43:04 volumio sudo[2615]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:04 volumio kernel: rotary-encoder rotary@18: gray May 13 23:43:04 volumio kernel: input: rotary@18 as /devices/platform/rotary@18/input/input17 May 13 23:43:04 volumio sudo[2615]: pam_unix(sudo:session): session closed for user root May 13 23:43:04 volumio systemd-udevd[2586]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:43:05 volumio sudo[2628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=4 active_low=1 gpio_pull=up keycode=20 May 13 23:43:05 volumio sudo[2628]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:05 volumio kernel: input: button@4 as /devices/platform/button@4/input/input18 May 13 23:43:05 volumio sudo[2628]: pam_unix(sudo:session): session closed for user root May 13 23:43:05 volumio systemd-udevd[2586]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:43:05 volumio go-librespot[1592]: time="2025-05-13T23:43:05+02:00" level=trace msg="sent dealer ping" May 13 23:43:05 volumio go-librespot[1592]: time="2025-05-13T23:43:05+02:00" level=trace msg="received dealer pong" May 13 23:43:06 volumio sudo[2641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:06 volumio sudo[2641]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:06 volumio sudo[2641]: pam_unix(sudo:session): session closed for user root May 13 23:43:06 volumio sudo[2645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:06 volumio sudo[2645]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:06 volumio sudo[2645]: pam_unix(sudo:session): session closed for user root May 13 23:43:07 volumio wpa_supplicant[978]: RRM: Ignoring radio measurement request: Not RRM network May 13 23:43:08 volumio go-librespot[1592]: time="2025-05-13T23:43:08+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:7tWMPQWh4eW6zyVKiP6eQ7/resume-point-revision/d0ce386d-59b6-41ce-b72d-db6d3a6ca005" May 13 23:43:08 volumio go-librespot[1592]: time="2025-05-13T23:43:08+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 286" May 13 23:43:08 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:08 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:10 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:10 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:10 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:11 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:11 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:11 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:11 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:11 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:11 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:12 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:12 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:12 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:12 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:12 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:12 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:15 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:15 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:16 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:16 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:17 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:17 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:17 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:17 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:17 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:17 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:19 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:19 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:21 volumio wpa_supplicant[978]: RRM: Ignoring radio measurement request: Not RRM network May 13 23:43:21 volumio volumio[1079]: info: VolumeController::SetAlsaVolume82 May 13 23:43:21 volumio volumio[1079]: info: CoreStateMachine::pushState May 13 23:43:21 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 May 13 23:43:21 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 23:43:21 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState May 13 23:43:21 volumio volumio[1079]: info: [GPIO_Control] Status changed: stop May 13 23:43:21 volumio volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 May 13 23:43:21 volumio volumio[1079]: SPOTIFY: SPOTIFY VOLUME 100 May 13 23:43:21 volumio volumio[1079]: SPOTIFY: VOLUMIO VOLUME 82 May 13 23:43:21 volumio volumio[1079]: SPOTIFY: DELTA VOLUME ENOUGH: true May 13 23:43:21 volumio volumio[1079]: info: Setting Spotify Volume from Volumio: 82 May 13 23:43:21 volumio volumio[1079]: info: VolumeController::SetAlsaVolume82 May 13 23:43:21 volumio volumio[1079]: info: CoreStateMachine::pushState May 13 23:43:21 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 May 13 23:43:21 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState May 13 23:43:21 volumio volumio[1079]: info: [GPIO_Control] Status changed: stop May 13 23:43:21 volumio volumio[1079]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 May 13 23:43:22 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:22 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:22 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:22 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:22 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:22 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:23 volumio go-librespot[1592]: time="2025-05-13T23:43:23+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:7tWMPQWh4eW6zyVKiP6eQ7/resume-point-revision/f6b619ea-2a37-432f-92a7-043970ea2410" May 13 23:43:23 volumio go-librespot[1592]: time="2025-05-13T23:43:23+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 286" May 13 23:43:23 volumio volumio[1079]: SPOTIFY: SETTING SPOTIFY VOLUME 82 May 13 23:43:23 volumio volumio[1079]: info: Sending Spotify command with payload to local API: /player/volume May 13 23:43:23 volumio go-librespot[1592]: time="2025-05-13T23:43:23+02:00" level=debug msg="update volume to 53738/65535" May 13 23:43:23 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:23 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:24 volumio go-librespot[1592]: time="2025-05-13T23:43:24+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 13 23:43:24 volumio go-librespot[1592]: time="2025-05-13T23:43:24+02:00" level=trace msg="emitting websocket event: volume" May 13 23:43:24 volumio volumio[1079]: SPOTIFY: received: {"type":"volume","data":{"value":82,"max":100}} May 13 23:43:24 volumio volumio[1079]: SPOTIFY: RECEIVED SPOTIFY VOLUME 82 May 13 23:43:24 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:24 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:24 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:24 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:24 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:24 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:25 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:25 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:32 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:32 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:32 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:33 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:33 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:34 volumio wpa_supplicant[978]: RRM: Ignoring radio measurement request: Not RRM network May 13 23:43:35 volumio go-librespot[1592]: time="2025-05-13T23:43:35+02:00" level=trace msg="sent dealer ping" May 13 23:43:35 volumio go-librespot[1592]: time="2025-05-13T23:43:35+02:00" level=trace msg="received dealer pong" May 13 23:43:36 volumio wpa_supplicant[978]: RRM: Ignoring radio measurement request: Not RRM network May 13 23:43:38 volumio go-librespot[1592]: time="2025-05-13T23:43:38+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:7tWMPQWh4eW6zyVKiP6eQ7/resume-point-revision/e2c8bbd7-6eeb-4524-8d09-4ebca7e23065" May 13 23:43:38 volumio go-librespot[1592]: time="2025-05-13T23:43:38+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 286" May 13 23:43:38 volumio wpa_supplicant[978]: RRM: Ignoring radio measurement request: Not RRM network May 13 23:43:40 volumio wpa_supplicant[978]: RRM: Ignoring radio measurement request: Not RRM network May 13 23:43:52 volumio volumio[1079]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] May 13 23:43:52 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder May 13 23:43:52 volumio sudo[2762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:52 volumio sudo[2762]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:52 volumio sudo[2762]: pam_unix(sudo:session): session closed for user root May 13 23:43:52 volumio sudo[2765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 23:43:52 volumio sudo[2765]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:52 volumio kernel: input: button@4 as /devices/platform/button@4/input/input19 May 13 23:43:52 volumio sudo[2765]: pam_unix(sudo:session): session closed for user root May 13 23:43:52 volumio sudo[2779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:52 volumio sudo[2779]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:52 volumio sudo[2779]: pam_unix(sudo:session): session closed for user root May 13 23:43:52 volumio sudo[2782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:52 volumio sudo[2782]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:52 volumio sudo[2782]: pam_unix(sudo:session): session closed for user root May 13 23:43:53 volumio sudo[2799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 23:43:53 volumio sudo[2799]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:53 volumio systemd-udevd[2770]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:43:53 volumio go-librespot[1592]: time="2025-05-13T23:43:53+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:7tWMPQWh4eW6zyVKiP6eQ7/resume-point-revision/92b6797f-9d04-409a-a282-33b762b130cb" May 13 23:43:53 volumio go-librespot[1592]: time="2025-05-13T23:43:53+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 286" May 13 23:43:53 volumio systemd-logind[662]: Failed to open /dev/input/event5: No such device May 13 23:43:53 volumio sudo[2799]: pam_unix(sudo:session): session closed for user root May 13 23:43:53 volumio sudo[2810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:53 volumio sudo[2810]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:53 volumio sudo[2810]: pam_unix(sudo:session): session closed for user root May 13 23:43:53 volumio sudo[2813]: 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 23:43:53 volumio sudo[2813]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:53 volumio kernel: rotary-encoder rotary@18: gray May 13 23:43:53 volumio kernel: input: rotary@18 as /devices/platform/rotary@18/input/input20 May 13 23:43:53 volumio sudo[2813]: pam_unix(sudo:session): session closed for user root May 13 23:43:53 volumio systemd-udevd[2770]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:43:54 volumio sudo[2826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=4 active_low=1 gpio_pull=up keycode=20 May 13 23:43:54 volumio sudo[2826]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:54 volumio kernel: input: button@4 as /devices/platform/button@4/input/input21 May 13 23:43:54 volumio sudo[2826]: pam_unix(sudo:session): session closed for user root May 13 23:43:54 volumio systemd-udevd[2770]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:43:55 volumio sudo[2838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:55 volumio sudo[2838]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:55 volumio sudo[2838]: pam_unix(sudo:session): session closed for user root May 13 23:43:55 volumio sudo[2842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:43:55 volumio sudo[2842]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:43:55 volumio sudo[2842]: pam_unix(sudo:session): session closed for user root May 13 23:43:56 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:43:56 volumio volumio[1079]: info: CURURI: music-library May 13 23:43:56 volumio volumio[1079]: info: Preload queue cleared May 13 23:43:56 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:43:56 volumio volumio[1079]: info: CURURI: music-library May 13 23:43:56 volumio volumio[1079]: info: Preload queue cleared May 13 23:43:57 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:43:57 volumio volumio[1079]: info: CURURI: music-library May 13 23:43:57 volumio volumio[1079]: info: Preload queue cleared May 13 23:43:58 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:58 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:58 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:43:58 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:43:59 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:43:59 volumio volumio[1079]: info: CURURI: music-library May 13 23:43:59 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:02 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:02 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:02 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:04 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:04 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:04 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:05 volumio go-librespot[1592]: time="2025-05-13T23:44:05+02:00" level=trace msg="received accesspoint ping" May 13 23:44:05 volumio go-librespot[1592]: time="2025-05-13T23:44:05+02:00" level=trace msg="received accesspoint pong ack" May 13 23:44:05 volumio go-librespot[1592]: time="2025-05-13T23:44:05+02:00" level=trace msg="sent dealer ping" May 13 23:44:05 volumio go-librespot[1592]: time="2025-05-13T23:44:05+02:00" level=trace msg="received dealer pong" May 13 23:44:07 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:07 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:07 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:07 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:07 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:07 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:07 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:44:07 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:44:08 volumio go-librespot[1592]: time="2025-05-13T23:44:08+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:7tWMPQWh4eW6zyVKiP6eQ7/resume-point-revision/010894b8-8a11-4833-a990-cd84b5c249a4" May 13 23:44:08 volumio go-librespot[1592]: time="2025-05-13T23:44:08+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 286" May 13 23:44:08 volumio volumio[1079]: info: CALLMETHOD: system_hardware dimmer dim up May 13 23:44:08 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: dimmer , dim May 13 23:44:09 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:09 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:09 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:10 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:10 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:10 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:10 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:10 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:10 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:11 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:11 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:11 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:11 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:13 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:13 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:13 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:14 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 13 23:44:14 volumio volumio[1079]: info: CURURI: music-library May 13 23:44:14 volumio volumio[1079]: info: Preload queue cleared May 13 23:44:23 volumio go-librespot[1592]: time="2025-05-13T23:44:23+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 286" May 13 23:44:23 volumio go-librespot[1592]: time="2025-05-13T23:44:23+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:7tWMPQWh4eW6zyVKiP6eQ7/resume-point-revision/83f858a8-2bc9-479d-9025-79c6b5ab32dd" May 13 23:44:35 volumio go-librespot[1592]: time="2025-05-13T23:44:35+02:00" level=trace msg="sent dealer ping" May 13 23:44:35 volumio go-librespot[1592]: time="2025-05-13T23:44:35+02:00" level=trace msg="received dealer pong" May 13 23:44:36 volumio volumio[1079]: info: CALLMETHOD: system_hardware rotaryencoder2 updateEncoder [object Object] May 13 23:44:36 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: rotaryencoder2 , updateEncoder May 13 23:44:36 volumio sudo[2951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:44:36 volumio sudo[2951]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:36 volumio sudo[2951]: pam_unix(sudo:session): session closed for user root May 13 23:44:36 volumio sudo[2954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 23:44:36 volumio sudo[2954]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:36 volumio kernel: input: button@4 as /devices/platform/button@4/input/input22 May 13 23:44:36 volumio sudo[2954]: pam_unix(sudo:session): session closed for user root May 13 23:44:36 volumio sudo[2969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:44:36 volumio sudo[2969]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:36 volumio sudo[2969]: pam_unix(sudo:session): session closed for user root May 13 23:44:36 volumio sudo[2972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:44:36 volumio sudo[2972]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:36 volumio sudo[2972]: pam_unix(sudo:session): session closed for user root May 13 23:44:36 volumio sudo[2976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r 0 May 13 23:44:36 volumio sudo[2976]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:36 volumio systemd-udevd[2959]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:44:36 volumio systemd-logind[662]: Failed to open /dev/input/event5: No such device May 13 23:44:36 volumio sudo[2976]: pam_unix(sudo:session): session closed for user root May 13 23:44:36 volumio sudo[2986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:44:36 volumio sudo[2986]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:36 volumio sudo[2986]: pam_unix(sudo:session): session closed for user root May 13 23:44:37 volumio sudo[2989]: 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 23:44:37 volumio sudo[2989]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:37 volumio kernel: rotary-encoder rotary@18: gray May 13 23:44:37 volumio kernel: input: rotary@18 as /devices/platform/rotary@18/input/input23 May 13 23:44:37 volumio sudo[2989]: pam_unix(sudo:session): session closed for user root May 13 23:44:37 volumio systemd-udevd[2959]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:44:38 volumio sudo[3002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=4 active_low=1 gpio_pull=up keycode=20 May 13 23:44:38 volumio sudo[3002]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:38 volumio go-librespot[1592]: time="2025-05-13T23:44:38+02:00" level=debug msg="skipping dealer message" uri="hm://herodotus/uri/spotify:episode:7tWMPQWh4eW6zyVKiP6eQ7/resume-point-revision/eed57c40-f9a2-49be-8508-c2dfa4c453b1" May 13 23:44:38 volumio go-librespot[1592]: time="2025-05-13T23:44:38+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 286" May 13 23:44:38 volumio kernel: input: button@4 as /devices/platform/button@4/input/input24 May 13 23:44:38 volumio sudo[3002]: pam_unix(sudo:session): session closed for user root May 13 23:44:38 volumio systemd-udevd[2959]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 13 23:44:39 volumio sudo[3014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:44:39 volumio sudo[3014]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:39 volumio sudo[3014]: pam_unix(sudo:session): session closed for user root May 13 23:44:39 volumio sudo[3018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 13 23:44:39 volumio sudo[3018]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 23:44:39 volumio sudo[3018]: pam_unix(sudo:session): session closed for user root May 13 23:44:40 volumio volumio[1079]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 23:44:40 volumio volumio[1079]: SyntaxError: Unexpected token + in JSON at position 0 May 13 23:44:40 volumio volumio[1079]: at JSON.parse () May 13 23:44:40 volumio volumio[1079]: at rotaryencoder2.emitDialCommand (/data/plugins/system_hardware/rotaryencoder2/index.js:980:72) May 13 23:44:40 volumio volumio[1079]: at Socket. (/data/plugins/system_hardware/rotaryencoder2/index.js:849:11) May 13 23:44:40 volumio volumio[1079]: at Socket.emit (events.js:315:20) May 13 23:44:40 volumio volumio[1079]: at addChunk (internal/streams/readable.js:309:12) May 13 23:44:40 volumio volumio[1079]: at readableAddChunk (internal/streams/readable.js:284:9) May 13 23:44:40 volumio volumio[1079]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 13 23:44:40 volumio volumio[1079]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 13 23:44:40 volumio volumio[1079]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 23:44:40 volumio sudo[3030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 23:43 May 13 23:44:40 volumio sudo[3030]: 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"