May 25 09:29:00 peppytest volumio[2441]: info: touch_display: Raspberry Pi Foundation touch screen detected. May 25 09:29:00 peppytest volumio[2441]: info: Starting Shairport Sync May 25 09:29:00 peppytest volumio[2441]: info: Starting Shairport Sync May 25 09:29:00 peppytest volumio[2441]: info: Starting Shairport Sync May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 09:29:00 peppytest volumio[2441]: info: Asound.conf file unchanged, so no further update is needed May 25 09:29:00 peppytest volumio[2441]: info: Output device has changed, restarting MPD May 25 09:29:00 peppytest volumio[2441]: info: Output device has changed, restarting Shairport Sync May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 09:29:00 peppytest volumio[2441]: info: Preparing to generate the ALSA configuration file May 25 09:29:00 peppytest volumio[2441]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 25 09:29:00 peppytest volumio[2441]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 25 09:29:00 peppytest volumio[2441]: info: Reading ALSA contributions from plugins. May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: Starting Shairport Sync May 25 09:29:00 peppytest volumio[2441]: info: Asound.conf file unchanged, so no further update is needed May 25 09:29:00 peppytest volumio[2441]: info: Output device has changed, restarting MPD May 25 09:29:00 peppytest volumio[2441]: info: Output device has changed, restarting Shairport Sync May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 09:29:00 peppytest volumio[2441]: info: Starting Shairport Sync May 25 09:29:00 peppytest volumio[2441]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 25 09:29:00 peppytest volumio[2441]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 25 09:29:00 peppytest volumio[2441]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 25 09:29:00 peppytest volumio[2441]: info: camilladsp service started and running in background, instance 1 May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:00 peppytest volumio[2441]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA May 25 09:29:00 peppytest volumio[2441]: info: FusionDsp loaded May 25 09:29:00 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:29:00 peppytest volumio[2441]: error: FusionDsp - Socket connection not established May 25 09:29:00 peppytest volumio[2441]: info: FusionDsp - Reporting Fusion DSP Enabled May 25 09:29:00 peppytest volumio[2441]: info: Adding Signal Path Element [object Object] May 25 09:29:00 peppytest volumio[2441]: info: Adding fusiondspeq DSP Signal Path Element May 25 09:29:00 peppytest volumio[2441]: info: FusionDsp - ---- installed callbackRead May 25 09:29:00 peppytest volumio[2441]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 25 09:29:00 peppytest volumio[2441]: info: Cannot mount NAS M1S at system boot, trial number 4 ,retrying in 5 seconds May 25 09:29:01 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 09:29:01 peppytest sudo[2490]: pam_unix(sudo:session): session closed for user root May 25 09:29:01 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 09:29:02 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 09:29:02 peppytest volumio[2441]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 25 09:29:02 peppytest volumio[2441]: SPOTIFY: BQAmiOgrsyhLuKC1vG61ILAJr1uP_TW5a4hGrgYT3iTj0LVfqdpfvdg51gr4rILeMp0Xw2a0MVihwFe0pEZ_hNXOe4lV9B8ADTB-dS_IlzXViSne3krABMchL21bPTlfSwJ5f0rB_ixXsIVMg-q7Mfs4Ud2wKtbgfXUUls9VdQlDshgDEcZmd2udOjyOdzSLzlhPR3nufKs8nwwRBkNiG4DOlg2CT60ZL9sKpf8PuwnZ6-iwfatwIFPGixaQ1iU_BSkehVy5t4W6lwzE4ms8mzGIXhwON3_3GUlb6HCG-IZB1GJh8YpV4tRQuCj_1Vj38KLkwwYOUoIkoiV5j7DA91qGjJ_Vh5HGz82YRRA8KM9oZMTbIAqHNZyE-6uo May 25 09:29:02 peppytest volumio[2441]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 25 09:29:02 peppytest volumio[2441]: info: New Spotify access token = BQAmiOgrsyhLuKC1vG61ILAJr1uP_TW5a4hGrgYT3iTj0LVfqdpfvdg51gr4rILeMp0Xw2a0MVihwFe0pEZ_hNXOe4lV9B8ADTB-dS_IlzXViSne3krABMchL21bPTlfSwJ5f0rB_ixXsIVMg-q7Mfs4Ud2wKtbgfXUUls9VdQlDshgDEcZmd2udOjyOdzSLzlhPR3nufKs8nwwRBkNiG4DOlg2CT60ZL9sKpf8PuwnZ6-iwfatwIFPGixaQ1iU_BSkehVy5t4W6lwzE4ms8mzGIXhwON3_3GUlb6HCG-IZB1GJh8YpV4tRQuCj_1Vj38KLkwwYOUoIkoiV5j7DA91qGjJ_Vh5HGz82YRRA8KM9oZMTbIAqHNZyE-6uo May 25 09:29:02 peppytest volumio[2441]: info: Spotify credentials grant success - running version from March 24, 2019 May 25 09:29:03 peppytest go-librespot[1819]: time="2025-05-25T09:29:03+02:00" level=trace msg="received accesspoint ping" May 25 09:29:03 peppytest go-librespot[1819]: time="2025-05-25T09:29:03+02:00" level=trace msg="received accesspoint pong ack" May 25 09:29:03 peppytest go-librespot[1819]: time="2025-05-25T09:29:03+02:00" level=trace msg="sent dealer ping" May 25 09:29:03 peppytest go-librespot[1819]: time="2025-05-25T09:29:03+02:00" level=trace msg="received dealer pong" May 25 09:29:05 peppytest volumio[2441]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 25 09:29:22 peppytest systemd[1]: systemd-timedated.service: Deactivated successfully. May 25 09:29:25 peppytest sudo[2623]: volumio : unable to resolve host peppytest: System error May 25 09:29:25 peppytest sudo[2623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 09:29:25 peppytest sudo[2623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:25 peppytest systemd[1]: Stopping mpd.service - Music Player Daemon... May 25 09:29:25 peppytest systemd[1]: mpd.service: Deactivated successfully. May 25 09:29:25 peppytest systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 09:29:25 peppytest systemd[1]: mpd.service: Consumed 2.855s CPU time. May 25 09:29:25 peppytest systemd[1]: mpd.socket: Deactivated successfully. May 25 09:29:25 peppytest systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 09:29:25 peppytest systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 09:29:26 peppytest systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 09:29:26 peppytest systemd[1]: Starting mpd.service - Music Player Daemon... May 25 09:29:28 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 09:29:28 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:29:28 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 09:29:28 peppytest volumio[2441]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion May 25 09:29:28 peppytest volumio[2441]: error: Plugin system_hardware ir_controller failed to complete 'onStart' in a timely fashion May 25 09:29:28 peppytest volumio[2441]: error: Plugin user_interface touch_display failed to complete 'onStart' in a timely fashion May 25 09:29:28 peppytest volumio[2441]: info: ------------------------------------------- May 25 09:29:28 peppytest volumio[2441]: info: ----- MyVolumio plugins startup ---- May 25 09:29:28 peppytest volumio[2441]: info: ------------------------------------------- May 25 09:29:28 peppytest volumio[2441]: info: [MyVolumio PluginManager] Fetching plans data.... May 25 09:29:30 peppytest sudo[2631]: volumio : unable to resolve host peppytest: System error May 25 09:29:30 peppytest sudo[2631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 09:29:30 peppytest sudo[2631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:30 peppytest volumio[2441]: error: FusionDsp - Reload WebSocket error: [object Object] May 25 09:29:33 peppytest go-librespot[1819]: time="2025-05-25T09:29:33+02:00" level=trace msg="sent dealer ping" May 25 09:29:33 peppytest go-librespot[1819]: time="2025-05-25T09:29:33+02:00" level=trace msg="received dealer pong" May 25 09:29:40 peppytest sudo[2612]: volumio : unable to resolve host peppytest: System error May 25 09:29:40 peppytest sudo[2612]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:29:40 peppytest sudo[2614]: volumio : unable to resolve host peppytest: System error May 25 09:29:40 peppytest sudo[2612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:40 peppytest sudo[2614]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:29:40 peppytest sudo[2612]: pam_unix(sudo:session): session closed for user root May 25 09:29:40 peppytest sudo[2614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:40 peppytest sudo[2614]: pam_unix(sudo:session): session closed for user root May 25 09:29:47 peppytest sudo[2681]: volumio : unable to resolve host peppytest: System error May 25 09:29:47 peppytest sudo[2683]: volumio : unable to resolve host peppytest: System error May 25 09:29:47 peppytest sudo[2681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness May 25 09:29:47 peppytest sudo[2681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:47 peppytest sudo[2683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 25 09:29:47 peppytest sudo[2683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:47 peppytest sudo[2681]: pam_unix(sudo:session): session closed for user root May 25 09:29:47 peppytest volumio[2441]: info: touch_display: File permissions for backlight brightness control set. May 25 09:29:47 peppytest sudo[2683]: pam_unix(sudo:session): session closed for user root May 25 09:29:47 peppytest volumio[2441]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 25 09:29:48 peppytest volumio[2441]: error: MyVolumio Plugin failed to start in a timely fashion May 25 09:29:48 peppytest volumio[2441]: [Metrics] CommandRouter: 66s 702.65ms May 25 09:29:48 peppytest volumio[2441]: info: CoreCommandRouter::volumiosetStartupVolume May 25 09:29:48 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:29:48 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:29:48 peppytest volumio[2441]: info: CoreCommandRouter::Close All Modals sent May 25 09:29:48 peppytest volumio[2441]: info: CoreCommandRouter::Close All Modals sent May 25 09:29:48 peppytest volumio[2441]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 25 09:29:48 peppytest volumio[2441]: info: FusionDsp - ---- read samplerate from file: 44100 May 25 09:29:48 peppytest volumio[2441]: info: camilladsp stopping service pid 2730... May 25 09:29:49 peppytest volumio[2441]: grep: /proc/2730/cmdline: binary file matches May 25 09:29:49 peppytest volumio[2441]: info: camilladsp service terminated, instance 1 May 25 09:29:49 peppytest volumio[2441]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 25 09:29:49 peppytest volumio[2441]: info: camilladsp service started and running in background, instance 1 May 25 09:29:49 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 25 09:29:49 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 09:29:49 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 25 09:29:50 peppytest sudo[2616]: volumio : unable to resolve host peppytest: System error May 25 09:29:50 peppytest sudo[2616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 25 09:29:50 peppytest sudo[2616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:50 peppytest sudo[2616]: pam_unix(sudo:session): session closed for user root May 25 09:29:50 peppytest volumio[2441]: info: Upmpdcli Daemon Started May 25 09:29:53 peppytest sudo[2621]: volumio : unable to resolve host peppytest: System error May 25 09:29:53 peppytest sudo[2621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 09:29:53 peppytest sudo[2621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:53 peppytest sudo[2621]: pam_unix(sudo:session): session closed for user root May 25 09:29:53 peppytest volumio[2441]: info: MPD Permissions set May 25 09:29:53 peppytest sudo[2629]: volumio : unable to resolve host peppytest: System error May 25 09:29:53 peppytest sudo[2629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 09:29:53 peppytest sudo[2629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:53 peppytest sudo[2629]: pam_unix(sudo:session): session closed for user root May 25 09:29:53 peppytest volumio[2441]: info: MPD Permissions set May 25 09:29:55 peppytest sudo[2660]: volumio : unable to resolve host peppytest: System error May 25 09:29:55 peppytest sudo[2660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc May 25 09:29:55 peppytest sudo[2660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:55 peppytest sudo[2660]: pam_unix(sudo:session): session closed for user root May 25 09:29:55 peppytest volumio[2441]: info: ir_controller: File permissions successfully set on /etc/lirc/*. May 25 09:29:55 peppytest volumio[2441]: info: ir_controller: LIRC correctly updated. May 25 09:29:55 peppytest sudo[2664]: volumio : unable to resolve host peppytest: System error May 25 09:29:55 peppytest sudo[2662]: volumio : unable to resolve host peppytest: System error May 25 09:29:55 peppytest sudo[2664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 25 09:29:55 peppytest sudo[2664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:55 peppytest volumio[2441]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! May 25 09:29:55 peppytest sudo[2662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 25 09:29:55 peppytest sudo[2662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:55 peppytest sudo[2666]: volumio : unable to resolve host peppytest: System error May 25 09:29:55 peppytest sudo[2666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 25 09:29:55 peppytest sudo[2666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:55 peppytest systemd[1]: Reloading. May 25 09:29:55 peppytest sudo[2669]: volumio : unable to resolve host peppytest: System error May 25 09:29:55 peppytest sudo[2669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 25 09:29:55 peppytest sudo[2669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:55 peppytest volumio[2441]: info: BOOT COMPLETED May 25 09:29:55 peppytest systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:29:55 peppytest systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 25 09:29:56 peppytest volumio[2441]: info: Adding plugin bluetooth to MyMusic Plugins May 25 09:29:56 peppytest volumio[2441]: info: Adding plugin multiroom to MyMusic Plugins May 25 09:29:56 peppytest volumio[2441]: info: Adding plugin metavolumio to MyMusic Plugins May 25 09:29:56 peppytest volumio[2441]: info: Adding plugin cd_controller to MyMusic Plugins May 25 09:29:56 peppytest volumio[2441]: info: Adding plugin smart_inputs to MyMusic Plugins May 25 09:29:56 peppytest volumio[2441]: info: Adding plugin tidalconnect to MyMusic Plugins May 25 09:29:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 25 09:29:56 peppytest sudo[2685]: volumio : unable to resolve host peppytest: System error May 25 09:29:56 peppytest sudo[2687]: volumio : unable to resolve host peppytest: System error May 25 09:29:56 peppytest sudo[2689]: volumio : unable to resolve host peppytest: System error May 25 09:29:56 peppytest sudo[2685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 09:29:56 peppytest sudo[2685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:56 peppytest sudo[2687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 09:29:56 peppytest sudo[2689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 09:29:56 peppytest sudo[2689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:56 peppytest sudo[2687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:56 peppytest sudo[2691]: volumio : unable to resolve host peppytest: System error May 25 09:29:56 peppytest sudo[2693]: volumio : unable to resolve host peppytest: System error May 25 09:29:56 peppytest sudo[2691]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 09:29:56 peppytest sudo[2691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:56 peppytest sudo[2693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 09:29:56 peppytest sudo[2693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:56 peppytest sudo[2691]: pam_unix(sudo:session): session closed for user root May 25 09:29:56 peppytest systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:29:56 peppytest systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:29:56 peppytest sudo[2704]: volumio : unable to resolve host peppytest: System error May 25 09:29:56 peppytest sudo[2704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 09:29:56 peppytest sudo[2704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:56 peppytest sudo[2706]: volumio : unable to resolve host peppytest: System error May 25 09:29:56 peppytest sudo[2706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 25 09:29:56 peppytest sudo[2706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:56 peppytest sudo[2708]: volumio : unable to resolve host peppytest: System error May 25 09:29:56 peppytest sudo[2708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 25 09:29:56 peppytest sudo[2708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:56 peppytest sudo[2706]: pam_unix(sudo:session): session closed for user root May 25 09:29:56 peppytest sudo[2666]: pam_unix(sudo:session): session closed for user root May 25 09:29:56 peppytest systemd[1]: Reloading. May 25 09:29:56 peppytest sudo[2722]: volumio : unable to resolve host peppytest: System error May 25 09:29:56 peppytest sudo[2722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 25 09:29:56 peppytest sudo[2722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:56 peppytest sudo[2737]: volumio : unable to resolve host peppytest: System error May 25 09:29:56 peppytest sudo[2737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 25 09:29:56 peppytest sudo[2737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:29:57 peppytest systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:29:57 peppytest systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:29:57 peppytest systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:29:57 peppytest systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:29:57 peppytest sudo[2664]: pam_unix(sudo:session): session closed for user root May 25 09:29:57 peppytest systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... May 25 09:29:57 peppytest sudo[2737]: pam_unix(sudo:session): session closed for user root May 25 09:29:57 peppytest systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 25 09:29:57 peppytest systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 25 09:29:57 peppytest systemd[1]: shairport-sync.service: Deactivated successfully. May 25 09:29:57 peppytest systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 09:29:57 peppytest systemd[1]: shairport-sync.service: Consumed 2.074s CPU time. May 25 09:29:57 peppytest systemd[1]: go-librespot-daemon.service: Deactivated successfully. May 25 09:29:57 peppytest systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 09:29:57 peppytest dbus-daemon[710]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.88' (uid=0 pid=2919 comm="timedatectl show --property=NTPSynchronized --valu") May 25 09:29:57 peppytest sudo[2662]: pam_unix(sudo:session): session closed for user root May 25 09:29:57 peppytest systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 09:29:57 peppytest go-librespot[2921]: go-librespot daemon starting... May 25 09:29:57 peppytest systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 25 09:29:57 peppytest sudo[2687]: pam_unix(sudo:session): session closed for user root May 25 09:29:57 peppytest sudo[2685]: pam_unix(sudo:session): session closed for user root May 25 09:29:57 peppytest sudo[2722]: pam_unix(sudo:session): session closed for user root May 25 09:29:57 peppytest systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 25 09:29:57 peppytest sudo[2669]: pam_unix(sudo:session): session closed for user root May 25 09:29:57 peppytest go-librespot[2922]: time="2025-05-25T09:29:57+02:00" level=info msg="running go-librespot 0.2.0" May 25 09:29:57 peppytest go-librespot[2922]: time="2025-05-25T09:29:57+02:00" level=debug msg="app state loaded" May 25 09:29:57 peppytest go-librespot[2922]: time="2025-05-25T09:29:57+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 09:29:57 peppytest sudo[2704]: pam_unix(sudo:session): session closed for user root May 25 09:29:58 peppytest sudo[2689]: pam_unix(sudo:session): session closed for user root May 25 09:29:58 peppytest dbus-daemon[710]: [system] Successfully activated service 'org.freedesktop.timedate1' May 25 09:29:58 peppytest systemd[1]: Started systemd-timedated.service - Time & Date Service. May 25 09:29:58 peppytest setdatetime-helper.sh[2918]: Time is already synchronized. May 25 09:29:58 peppytest systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 25 09:29:58 peppytest systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 25 09:29:58 peppytest volumio[2441]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 25 09:29:58 peppytest volumio[2441]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 25 09:29:58 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:29:58 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:29:58 peppytest volumio[2441]: info: Starting MyVolumio Remote Streaming Endpoints May 25 09:29:58 peppytest volumio[2441]: info: MyVolumio login type: Token May 25 09:29:58 peppytest volumio[2441]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 25 09:29:58 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 25 09:30:00 peppytest volumio[2441]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 25 09:30:00 peppytest volumio[2441]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 25 09:30:00 peppytest volumio[2441]: info: Streaming services startup May 25 09:30:00 peppytest volumio[2441]: info: Starting Streaming Daemon May 25 09:30:00 peppytest volumio[2441]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 25 09:30:00 peppytest volumio[2441]: error: Failed to retrieve user informations: Error: getaddrinfo ENOTFOUND api.spotify.com May 25 09:30:00 peppytest volumio[2441]: error: Spotify credentials failed to read user data: May 25 09:30:00 peppytest volumio[2441]: info: An error occurred while initializing Spotify Browsing facility: May 25 09:30:00 peppytest volumio[2441]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 25 09:30:00 peppytest volumio[2441]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 25 09:30:00 peppytest volumio[2441]: info: Shairport-Sync Started May 25 09:30:00 peppytest volumio[2441]: Error adding Membership: Error: addMembership EINVAL May 25 09:30:00 peppytest volumio[2441]: info: touch_display: systemctl daemon-reload succeeded. May 25 09:30:00 peppytest volumio[2441]: info: MPD Permissions set May 25 09:30:00 peppytest volumio[2441]: info: Shairport-Sync Started May 25 09:30:00 peppytest volumio[2441]: info: MPD Permissions set May 25 09:30:00 peppytest volumio[2441]: info: Shairport-Sync Started May 25 09:30:00 peppytest volumio[2441]: info: Shairport-Sync Started May 25 09:30:00 peppytest volumio[2441]: info: Shairport-Sync Started May 25 09:30:03 peppytest volumio[2441]: info: go-librespot daemon successfully initialized May 25 09:30:06 peppytest volumio[2441]: info: Initializing connection to go-librespot Websocket May 25 09:30:06 peppytest go-librespot[2922]: time="2025-05-25T09:30:06+02:00" level=debug msg="new websocket client" May 25 09:30:06 peppytest volumio[2441]: info: Connection to go-librespot Websocket established May 25 09:30:08 peppytest sudo[2785]: root : unable to resolve host peppytest: System error May 25 09:30:08 peppytest sudo[2785]: sudo: unable to resolve host peppytest: System error May 25 09:30:08 peppytest sudo[2785]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 09:30:08 peppytest sudo[2785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 09:30:08 peppytest sudo[2785]: pam_unix(sudo:session): session closed for user root May 25 09:30:08 peppytest systemd[1]: mpd.service: Deactivated successfully. May 25 09:30:08 peppytest systemd[1]: Stopped mpd.service - Music Player Daemon. May 25 09:30:08 peppytest systemd[1]: mpd.socket: Deactivated successfully. May 25 09:30:08 peppytest systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 25 09:30:08 peppytest systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 25 09:30:08 peppytest systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 25 09:30:08 peppytest systemd[1]: Starting mpd.service - Music Player Daemon... May 25 09:30:08 peppytest sudo[2945]: volumio : unable to resolve host peppytest: System error May 25 09:30:08 peppytest sudo[2945]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 25 09:30:08 peppytest sudo[2945]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:30:08 peppytest sudo[2971]: root : unable to resolve host peppytest: System error May 25 09:30:08 peppytest sudo[2971]: sudo: unable to resolve host peppytest: System error May 25 09:30:08 peppytest sudo[2971]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 25 09:30:08 peppytest sudo[2971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 25 09:30:08 peppytest sudo[2971]: pam_unix(sudo:session): session closed for user root May 25 09:30:08 peppytest sudo[2945]: pam_unix(sudo:session): session closed for user root May 25 09:30:08 peppytest volumio[2441]: error: Cannot start Volumio Streaming Daemon May 25 09:30:08 peppytest volumio[2441]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 25 09:30:08 peppytest volumio[2441]: sudo: unable to resolve host peppytest: System error May 25 09:30:08 peppytest volumio[2441]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 25 09:30:08 peppytest sudo[2951]: volumio : unable to resolve host peppytest: System error May 25 09:30:08 peppytest sudo[2951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service May 25 09:30:08 peppytest sudo[2951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:30:08 peppytest lircd[1914]: lircd-0.10.1[1914]: Notice: caught signal May 25 09:30:08 peppytest lircd-0.10.1[1914]: Notice: caught signal May 25 09:30:08 peppytest systemd[1]: Stopping lircd.service - Flexible IR remote input/output application support... May 25 09:30:08 peppytest systemd[1]: lircd.service: Deactivated successfully. May 25 09:30:08 peppytest systemd[1]: Stopped lircd.service - Flexible IR remote input/output application support. May 25 09:30:08 peppytest systemd[1]: irexec.service: Deactivated successfully. May 25 09:30:08 peppytest sudo[2953]: volumio : unable to resolve host peppytest: System error May 25 09:30:08 peppytest sudo[2953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 25 09:30:08 peppytest systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... May 25 09:30:08 peppytest sudo[2953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:30:08 peppytest sudo[2953]: pam_unix(sudo:session): session closed for user root May 25 09:30:08 peppytest volumio[2441]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 25 09:30:08 peppytest volumio[2441]: info: touch_display: Volumio Kiosk started. May 25 09:30:08 peppytest lircd-setup[2976]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. May 25 09:30:08 peppytest lircd-setup[2976]: parser = configparser.SafeConfigParser() May 25 09:30:08 peppytest systemd[1]: lircd-setup.service: Deactivated successfully. May 25 09:30:08 peppytest systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. May 25 09:30:08 peppytest volumio[2441]: info: CoreCommandRouter::volumioGetState May 25 09:30:08 peppytest volumio[2441]: info: CorePlayQueue::getTrack 0 May 25 09:30:08 peppytest volumio[2441]: info: CoreCommandRouter::volumioGetState May 25 09:30:08 peppytest volumio[2441]: info: CorePlayQueue::getTrack 0 May 25 09:30:09 peppytest systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... May 25 09:30:09 peppytest lircd-0.10.1[2980]: Info: lircd: Opening log, level: Info May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Version: lircd 0.10.1 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: System info: Linux peppytest 6.12.27-v7l+ #1876 SMP Tue May 6 15:10:05 BST 2025 armv7l GNU/Linux May 25 09:30:09 peppytest lircd-0.10.1[2980]: Info: Initial device: /dev/lirc0 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Info: [lirc] protocol is enabled May 25 09:30:09 peppytest lircd-0.10.1[2980]: Info: Initial device: /dev/lirc0 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Info: [lirc] protocol is enabled May 25 09:30:09 peppytest lircd[2980]: lircd-0.10.1[2980]: Info: lircd: Opening log, level: Info May 25 09:30:09 peppytest lircd[2980]: lircd-0.10.1[2980]: Notice: Using systemd fd May 25 09:30:09 peppytest lircd[2980]: lircd-0.10.1[2980]: Warning: Running as root May 25 09:30:09 peppytest lircd[2980]: lircd-0.10.1[2980]: Info: Using remote: xoro. May 25 09:30:09 peppytest lircd[2980]: lircd-0.10.1[2980]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: driver: default May 25 09:30:09 peppytest systemd[1]: Started lircd.service - Flexible IR remote input/output application support. May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: output: /var/run/lirc/lircd May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: nodaemon: 1 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: logfile: syslog May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: immediate-init: 0 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: permission: 666 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: driver-options: May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: listen: 0 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: connect: (null) May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: userelease: 0 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: effective_user: (null) May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: release_suffix: _EVUP May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: allow_simulate: 0 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: repeat_max: 600 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: configfile: /etc/lirc/lircd.conf May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Options: dynamic_codes: (null) May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Current driver: default May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Driver API version: 3 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Driver version: 0.10.0 May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 25 09:30:09 peppytest lircd-0.10.1[2980]: Info: lircd: Opening log, level: Info May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: Using systemd fd May 25 09:30:09 peppytest lircd-0.10.1[2980]: Warning: Running as root May 25 09:30:09 peppytest lircd-0.10.1[2980]: Info: Using remote: xoro. May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 25 09:30:09 peppytest sudo[2951]: pam_unix(sudo:session): session closed for user root May 25 09:30:09 peppytest volumio[2441]: info: ir_controller: systemctl restart lircd.service succeeded. May 25 09:30:09 peppytest volumio[2441]: info: touch_display: X display number found: 0 May 25 09:30:09 peppytest volumio[2441]: info: touch_display: X display number found: 0 May 25 09:30:09 peppytest sudo[2986]: volumio : unable to resolve host peppytest: System error May 25 09:30:09 peppytest sudo[2986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 25 09:30:09 peppytest sudo[2986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:30:09 peppytest volumio[2441]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 25 09:30:09 peppytest volumio[2441]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 25 09:30:09 peppytest systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). May 25 09:30:09 peppytest lircd[2980]: lircd-0.10.1[2980]: Notice: accepted new client on /var/run/lirc/lircd May 25 09:30:09 peppytest lircd[2980]: lircd-0.10.1[2980]: Info: [lirc] protocol is enabled May 25 09:30:09 peppytest lircd-0.10.1[2980]: Notice: accepted new client on /var/run/lirc/lircd May 25 09:30:09 peppytest lircd-0.10.1[2980]: Info: [lirc] protocol is enabled May 25 09:30:09 peppytest sudo[2986]: pam_unix(sudo:session): session closed for user root May 25 09:30:09 peppytest volumio[2441]: info: touch_display: X display number found: 0 May 25 09:30:09 peppytest volumio[2441]: info: ir_controller: systemctl restart irexec.service succeeded. May 25 09:30:09 peppytest volumio[2441]: info: CoreCommandRouter::volumioGetState May 25 09:30:09 peppytest volumio[2441]: info: CorePlayQueue::getTrack 0 May 25 09:30:09 peppytest volumio[2441]: info: touch_display: Setting screensaver timeout to 120 seconds. May 25 09:30:09 peppytest volumio[2441]: info: Getting Spotify volume May 25 09:30:09 peppytest volumio[2441]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 May 25 09:30:10 peppytest mpd[2974]: 2025-05-25T09:30:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 25 09:30:10 peppytest volumio[2441]: info: MyVolumio token set successfully May 25 09:30:10 peppytest volumio[2441]: info: MYVOLUMIO: Adding device May 25 09:30:10 peppytest volumio[2441]: info: MYVOLUMIO: Evaluating Server May 25 09:30:10 peppytest volumio[2441]: info: CoreCommandRouter::volumioGetState May 25 09:30:10 peppytest volumio[2441]: info: CorePlayQueue::getTrack 0 May 25 09:30:10 peppytest volumio[2441]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 25 09:30:10 peppytest volumio[2441]: SPOTIFY: SPOTIFY VOLUME undefined May 25 09:30:10 peppytest volumio[2441]: SPOTIFY: VOLUMIO VOLUME 100 May 25 09:30:10 peppytest volumio[2441]: info: Aligning Spotify Volume to Volumio Volume May 25 09:30:10 peppytest volumio[2441]: info: CoreCommandRouter::volumioGetState May 25 09:30:10 peppytest volumio[2441]: info: CorePlayQueue::getTrack 0 May 25 09:30:10 peppytest volumio[2441]: info: Setting Spotify Volume from Volumio: 100 May 25 09:30:10 peppytest volumio[2441]: info: MyVolumio Plan changed: premium May 25 09:30:10 peppytest volumio[2441]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 25 09:30:10 peppytest volumio[2441]: info: Removing browser output: myVolumio user plan is not superstar May 25 09:30:10 peppytest volumio[2441]: info: Removing audio output: May 25 09:30:10 peppytest volumio[2441]: info: MYVOLUMIO: Adding device May 25 09:30:10 peppytest volumio[2441]: info: MYVOLUMIO: Evaluating Server May 25 09:30:10 peppytest volumio[2441]: info: Remote config written successfully May 25 09:30:10 peppytest volumio[2441]: info: Starting Tunnel 1 May 25 09:30:10 peppytest volumio[2441]: info: Starting Tunnel Connection Checker May 25 09:30:11 peppytest systemd[1]: Started mpd.service - Music Player Daemon. May 25 09:30:11 peppytest sudo[2693]: pam_unix(sudo:session): session closed for user root May 25 09:30:11 peppytest sudo[2631]: pam_unix(sudo:session): session closed for user root May 25 09:30:11 peppytest sudo[2623]: pam_unix(sudo:session): session closed for user root May 25 09:30:11 peppytest sudo[2708]: pam_unix(sudo:session): session closed for user root May 25 09:30:11 peppytest volumio[2441]: info: MYVolumio Device enabled May 25 09:30:11 peppytest volumio[2441]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 25 09:30:11 peppytest volumio[2441]: info: MyVolumio status changed May 25 09:30:11 peppytest volumio[2441]: info: Streaming services startup May 25 09:30:11 peppytest volumio[2441]: info: Starting Streaming Daemon May 25 09:30:11 peppytest volumio[2441]: error: MPD error: The expression evaluated to a falsy value: May 25 09:30:11 peppytest volumio[2441]: assert.ok(self.idling) May 25 09:30:11 peppytest volumio[2441]: error: The expression evaluated to a falsy value: May 25 09:30:11 peppytest volumio[2441]: assert.ok(self.idling) May 25 09:30:11 peppytest volumio[2441]: error: MPD error: The expression evaluated to a falsy value: May 25 09:30:11 peppytest volumio[2441]: assert.ok(self.idling) May 25 09:30:11 peppytest volumio[2441]: error: The expression evaluated to a falsy value: May 25 09:30:11 peppytest volumio[2441]: assert.ok(self.idling) May 25 09:30:11 peppytest volumio[2441]: error: MPD error: The expression evaluated to a falsy value: May 25 09:30:11 peppytest volumio[2441]: assert.ok(self.idling) May 25 09:30:11 peppytest volumio[2441]: error: The expression evaluated to a falsy value: May 25 09:30:11 peppytest volumio[2441]: assert.ok(self.idling) May 25 09:30:11 peppytest volumio[2441]: info: MPD running with PID2974 May 25 09:30:11 peppytest volumio[2441]: ,establishing connection May 25 09:30:11 peppytest volumio[2441]: error: updateQueue error: null May 25 09:30:11 peppytest volumio[2441]: SPOTIFY: SETTING SPOTIFY VOLUME 100 May 25 09:30:11 peppytest volumio[2441]: info: Sending Spotify command with payload to local API: /player/volume May 25 09:30:11 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 25 09:30:11 peppytest volumio[2441]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! May 25 09:30:11 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 25 09:30:12 peppytest volumio[2441]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart May 25 09:30:12 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 25 09:30:14 peppytest volumio[2441]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 25 09:30:26 peppytest go-librespot[2922]: time="2025-05-25T09:30:25+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" May 25 09:30:26 peppytest systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE May 25 09:30:26 peppytest systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. May 25 09:30:28 peppytest systemd[1]: systemd-timedated.service: Deactivated successfully. May 25 09:30:29 peppytest systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. May 25 09:30:29 peppytest systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. May 25 09:30:29 peppytest systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. May 25 09:30:29 peppytest go-librespot[3067]: go-librespot daemon starting... May 25 09:30:29 peppytest go-librespot[3068]: time="2025-05-25T09:30:29+02:00" level=info msg="running go-librespot 0.2.0" May 25 09:30:29 peppytest go-librespot[3068]: time="2025-05-25T09:30:29+02:00" level=debug msg="app state loaded" May 25 09:30:29 peppytest go-librespot[3068]: time="2025-05-25T09:30:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 25 09:30:42 peppytest dbus-daemon[710]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30004ms) May 25 09:30:47 peppytest nmbd[1015]: [2025/05/25 09:30:47.489272, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 25 09:30:47 peppytest nmbd[1015]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.141 for name WORKGROUP<1d>. May 25 09:30:47 peppytest nmbd[1015]: This response was from IP 192.168.1.15, reporting an IP address of 192.168.1.15. May 25 09:30:47 peppytest nmbd[1015]: [2025/05/25 09:30:47.489595, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 25 09:30:47 peppytest nmbd[1015]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.138 for name WORKGROUP<1d>. May 25 09:30:47 peppytest nmbd[1015]: This response was from IP 192.168.1.15, reporting an IP address of 192.168.1.15. May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=info msg="zeroconf server listening on port 34527" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="obtained new client token: AACrrnXCjhdZrairRjZsO7pDSf6XMdqSLeJTmm/lbyhs3Vxi+5K54McBtimeoguUp+ifXyiayYkWhxdQaoWcmlrMW//ffbUx1p/Rj/6y5KI2YGT6RHJY5euGWYmt6k1N4LFt5RRayx5f6dkugkqhL59TxKiAxAucL2zhrb1pQf8vQVP5xVmGnaKK7Mbjf4UMLVSG67jiSX6Ahpqd96WV8m5yva/p+OtTMZ1RAKYdADo0bANzTHArJRMOgwQr" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="completed keyexchange" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="completed challenge" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=info msg="authenticated AP as z4ujt0w8jsm3yt93kz6hrseoy" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=info msg="authenticated Login5 as z4ujt0w8jsm3yt93kz6hrseoy" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="initializing zeroconf session, username: z4ujt0w8jsm3yt93kz6hrseoy" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="dealer connection opened" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=trace msg="starting accesspoint recv loop" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=trace msg="starting dealer recv loop" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=trace msg="received accesspoint ping" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="received connection id: OTgxODk3MmQtMTI5Ni00NTAzLTkwZTMtMjYyZGY3ZTNhYWJkK2RlYWxlcit0Y3A6Ly8wYWNhNTg4OC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArMzZDMkIxOUE4MDc2NTkzQzI1QTY2NTJGQzcyQ0EwOTg3OUE3Mzg5QTA0QkY1OTY3MjQyMTYwMTIxQTc0OENEQg==" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 25 09:30:48 peppytest go-librespot[3068]: time="2025-05-25T09:30:48+02:00" level=trace msg="received accesspoint pong ack" May 25 09:30:56 peppytest sudo[3035]: volumio : unable to resolve host peppytest: System error May 25 09:30:56 peppytest sudo[3035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 25 09:30:56 peppytest sudo[3035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:30:56 peppytest sudo[3035]: pam_unix(sudo:session): session closed for user root May 25 09:30:56 peppytest volumio[2441]: sudo: unable to resolve host peppytest: System error May 25 09:30:56 peppytest volumio[2441]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists May 25 09:30:56 peppytest volumio[2441]: info: MRS: MultiRoom plugin initialized May 25 09:30:56 peppytest volumio[2441]: info: MRS: STOPPING SNAPCLIENT May 25 09:30:56 peppytest volumio[2441]: info: MRS: Snap server stop May 25 09:30:56 peppytest volumio[2441]: info: MRS: STOPPING volumioStreaming May 25 09:30:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 25 09:30:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 25 09:30:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 25 09:30:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 25 09:30:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 25 09:30:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 25 09:30:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 25 09:30:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 25 09:30:56 peppytest volumio[2441]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 25 09:30:56 peppytest volumio[2441]: info: Preparing to generate the ALSA configuration file May 25 09:30:56 peppytest volumio[2441]: info: Discovery: Disconnected from remote: 192.168.1.200 May 25 09:30:56 peppytest volumio[2441]: info: MRS: Pushing multiroomSync output for this device May 25 09:30:56 peppytest volumio[2441]: info: MRS: Pushing multiroomSync output May 25 09:30:56 peppytest volumio[2441]: info: Adding audio output: May 25 09:30:56 peppytest volumio[2441]: info: Adding audio output: May 25 09:30:56 peppytest volumio[2441]: info: Discovery: Disconnected from remote: 192.168.1.199 May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::volumioGetBrowseSources May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 09:30:56 peppytest volumio[2441]: error: updateQueue error: null May 25 09:30:56 peppytest volumio[2441]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up May 25 09:30:56 peppytest volumio[2441]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 25 09:30:56 peppytest volumio[2441]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 25 09:30:56 peppytest volumio[2441]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 25 09:30:56 peppytest volumio[2441]: info: Reading ALSA contributions from plugins. May 25 09:30:56 peppytest volumio[2441]: info: Setting Geolocation for MyVolumio to eu5 May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:30:56 peppytest volumio[2441]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:30:56 peppytest volumio[2441]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 09:30:56 peppytest volumio[2441]: Error: socket hang up May 25 09:30:56 peppytest volumio[2441]: at connResetException (node:internal/errors:720:14) May 25 09:30:56 peppytest volumio[2441]: at Socket.socketOnEnd (node:_http_client:519:23) May 25 09:30:56 peppytest volumio[2441]: at Socket.emit (node:events:526:35) May 25 09:30:56 peppytest volumio[2441]: at endReadableNT (node:internal/streams/readable:1376:12) May 25 09:30:56 peppytest volumio[2441]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { May 25 09:30:56 peppytest volumio[2441]: code: 'ECONNRESET', May 25 09:30:56 peppytest volumio[2441]: response: undefined May 25 09:30:56 peppytest volumio[2441]: } May 25 09:30:56 peppytest volumio[2441]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 09:30:58 peppytest sudo[3030]: volumio : unable to resolve host peppytest: System error May 25 09:30:58 peppytest sudo[3030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 25 09:30:58 peppytest sudo[3030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:30:58 peppytest systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 25 09:30:58 peppytest sudo[3030]: pam_unix(sudo:session): session closed for user root May 25 09:30:58 peppytest dbus-daemon[710]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.95' (uid=0 pid=3160 comm="timedatectl show --property=NTPSynchronized --valu") May 25 09:30:58 peppytest systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 25 09:30:58 peppytest dbus-daemon[710]: [system] Successfully activated service 'org.freedesktop.timedate1' May 25 09:30:58 peppytest systemd[1]: Started systemd-timedated.service - Time & Date Service. May 25 09:30:58 peppytest setdatetime-helper.sh[3159]: Time is already synchronized. May 25 09:30:58 peppytest systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 25 09:30:58 peppytest systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 25 09:31:01 peppytest sudo[3136]: volumio : unable to resolve host peppytest: System error May 25 09:31:01 peppytest sudo[3136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 25 09:31:01 peppytest sudo[3136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:31:01 peppytest sudo[3138]: volumio : unable to resolve host peppytest: System error May 25 09:31:01 peppytest sudo[3138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 25 09:31:01 peppytest sudo[3138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:31:01 peppytest sudo[3140]: volumio : unable to resolve host peppytest: System error May 25 09:31:01 peppytest sudo[3140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 25 09:31:01 peppytest sudo[3140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:31:01 peppytest sudo[3136]: pam_unix(sudo:session): session closed for user root May 25 09:31:01 peppytest sudo[3138]: pam_unix(sudo:session): session closed for user root May 25 09:31:01 peppytest sudo[3140]: pam_unix(sudo:session): session closed for user root May 25 09:31:01 peppytest sudo[3142]: volumio : unable to resolve host peppytest: System error May 25 09:31:01 peppytest sudo[3142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 25 09:31:01 peppytest sudo[3142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:31:01 peppytest sudo[3142]: pam_unix(sudo:session): session closed for user root May 25 09:31:08 peppytest go-librespot[3068]: time="2025-05-25T09:31:08+02:00" level=debug msg="put connect state because NEW_DEVICE" May 25 09:31:18 peppytest go-librespot[3068]: time="2025-05-25T09:31:18+02:00" level=trace msg="sent dealer ping" May 25 09:31:18 peppytest go-librespot[3068]: time="2025-05-25T09:31:18+02:00" level=trace msg="received dealer pong" May 25 09:31:25 peppytest sudo[3157]: volumio : unable to resolve host peppytest: System error May 25 09:31:25 peppytest sudo[3157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-25 09:29' May 25 09:31:25 peppytest sudo[3157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"