-- Logs begin at Wed 2025-07-09 09:45:41 CEST, end at Wed 2025-07-09 09:47:39 CEST. -- Jul 09 09:46:00 integro wireless.js[2837]: trying... Jul 09 09:46:00 integro sudo[3211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:00 integro sudo[3211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:00 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:00 integro sudo[3211]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:00 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:00 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:01 integro systemd[1]: systemd-rfkill.service: Succeeded. Jul 09 09:46:01 integro wireless.js[2837]: trying... Jul 09 09:46:01 integro sudo[3219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:01 integro sudo[3219]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:01 integro sudo[3219]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:02 integro wireless.js[2837]: trying... Jul 09 09:46:02 integro sudo[3222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:02 integro sudo[3222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:02 integro sudo[3222]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:03 integro wireless.js[2837]: trying... Jul 09 09:46:03 integro sudo[3225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:03 integro sudo[3225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:03 integro sudo[3225]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:04 integro wireless.js[2837]: trying... Jul 09 09:46:04 integro sudo[3228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:04 integro sudo[3228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:04 integro sudo[3228]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:05 integro wireless.js[2837]: trying... Jul 09 09:46:05 integro sudo[3231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:05 integro sudo[3231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:05 integro sudo[3231]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:05 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:05 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:05 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:06 integro wireless.js[2837]: trying... Jul 09 09:46:06 integro sudo[3239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:06 integro sudo[3239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:06 integro sudo[3239]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:07 integro wireless.js[2837]: trying... Jul 09 09:46:07 integro sudo[3242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:07 integro sudo[3242]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:07 integro sudo[3242]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:08 integro wireless.js[2837]: trying... Jul 09 09:46:08 integro sudo[3245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:08 integro sudo[3245]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:08 integro sudo[3245]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:09 integro volumio-remote-updater[2827]: [2025-07-09 09:46:09] [info] asio async_connect error: system:111 (Connection refused) Jul 09 09:46:09 integro volumio-remote-updater[2827]: [2025-07-09 09:46:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 09 09:46:09 integro volumio-remote-updater[2827]: [2025-07-09 09:46:09] [error] handle_connect error: Underlying Transport Error Jul 09 09:46:09 integro wireless.js[2837]: trying... Jul 09 09:46:09 integro sudo[3248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:09 integro sudo[3248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:09 integro sudo[3248]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:10 integro wireless.js[2837]: trying... Jul 09 09:46:10 integro sudo[3251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:10 integro sudo[3251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:10 integro sudo[3251]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:10 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:10 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:10 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:11 integro wireless.js[2837]: trying... Jul 09 09:46:11 integro sudo[3262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 09 09:46:11 integro sudo[3262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:11 integro sudo[3262]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:12 integro wireless.js[2837]: Overtime, starting plan B Jul 09 09:46:12 integro systemd[1]: Started Wireless Services. Jul 09 09:46:12 integro systemd[1]: Started Volumio Backend Module. Jul 09 09:46:12 integro systemd[1]: Started Volumio Cpu Tweaker. Jul 09 09:46:12 integro systemd[1]: Started Start Volumio Kiosk. Jul 09 09:46:12 integro startkiosk.sh[3277]: Starting Volumio Kiosk with arguments for cursor display Jul 09 09:46:12 integro volumio-cpu-tweak[3268]: Setting RT Priority for mpd Jul 09 09:46:12 integro volumio-cpu-tweak[3268]: Setting MPD Affinity Jul 09 09:46:12 integro volumio-cpu-tweak[3268]: pid 3100's current affinity mask: f Jul 09 09:46:12 integro volumio-cpu-tweak[3268]: pid 3100's new affinity mask: 3 Jul 09 09:46:13 integro volumio-cpu-tweak[3268]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 09 09:46:13 integro volumio-cpu-tweak[3268]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 09 09:46:13 integro systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 09 09:46:13 integro startkiosk.sh[3277]: X.Org X Server 1.20.4 Jul 09 09:46:13 integro startkiosk.sh[3277]: X Protocol Version 11, Revision 0 Jul 09 09:46:13 integro startkiosk.sh[3277]: Build Operating System: Linux 4.19.0-18-armmp-lpae armv7l Debian Jul 09 09:46:13 integro startkiosk.sh[3277]: Current Operating System: Linux integro 4.9.241 #4 SMP PREEMPT Wed Jun 4 18:07:40 CEST 2025 aarch64 Jul 09 09:46:13 integro startkiosk.sh[3277]: Kernel command line: imgpart=UUID=cd8a73eb-86c7-42d3-815e-c7111e338750 imgfile=/volumio_current.sqsh bootpart=UUID=0054-B6D8 datapart=UUID=9cf95ca3-029d-400a-b215-ac18d8259868 uuidconfig=env.system.txt hwdevice=VIM3L console=tty0 console=ttyS0,115200n8 no_console_suspend consoleblank=0 logo=osd0,loaded,0x3d800000,panel vout=panel,enable hdmimode=1080p60hz fsck.repair=yes net.ifnames=0 ddr_size= wol_enable= mac=02:00:00:31:0e:01 fan=auto hwver=VIM3.V12 coherent_pool=2M quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear splash bootdelay=7 Jul 09 09:46:13 integro startkiosk.sh[3277]: Build Date: 18 December 2021 09:05:36AM Jul 09 09:46:13 integro startkiosk.sh[3277]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Jul 09 09:46:13 integro startkiosk.sh[3277]: Current version of pixman: 0.36.0 Jul 09 09:46:13 integro startkiosk.sh[3277]: Before reporting problems, check http://wiki.x.org Jul 09 09:46:13 integro startkiosk.sh[3277]: to make sure that you have the latest version. Jul 09 09:46:13 integro startkiosk.sh[3277]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 09 09:46:13 integro startkiosk.sh[3277]: (++) from command line, (!!) notice, (II) informational, Jul 09 09:46:13 integro startkiosk.sh[3277]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 09 09:46:13 integro startkiosk.sh[3277]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Jul 9 09:46:13 2025 Jul 09 09:46:13 integro startkiosk.sh[3277]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 09 09:46:13 integro kernel: fb: osd[0] canvas.idx =0x40 Jul 09 09:46:13 integro kernel: fb: osd[0] canvas.addr=0x70000000 Jul 09 09:46:13 integro kernel: fb: osd[0] canvas.width=7680 Jul 09 09:46:13 integro kernel: fb: osd[0] canvas.height=1080 Jul 09 09:46:13 integro kernel: fb: osd[0] frame.width=1920 Jul 09 09:46:13 integro kernel: fb: osd[0] frame.height=1080 Jul 09 09:46:13 integro kernel: fb: osd[0] out_addr_id =0x1 Jul 09 09:46:13 integro kernel: fb: osd[0] enable: 0 (Xorg) Jul 09 09:46:14 integro kernel: fb: osd[0] enable: 1 (Xorg) Jul 09 09:46:14 integro systemd[1]: systemd-fsckd.service: Succeeded. Jul 09 09:46:15 integro kernel: fb: osd[0] enable: 1 (Xorg) Jul 09 09:46:15 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:15 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:15 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:16 integro volumio[3267]: info: ------------------------------------------- Jul 09 09:46:16 integro volumio[3267]: info: ----- Volumio3 ---- Jul 09 09:46:16 integro volumio[3267]: info: ------------------------------------------- Jul 09 09:46:16 integro volumio[3267]: info: ----- System startup ---- Jul 09 09:46:16 integro volumio[3267]: info: ------------------------------------------- Jul 09 09:46:16 integro sh[2762]: timed out Jul 09 09:46:16 integro sh[2762]: dhcpcd exited Jul 09 09:46:16 integro dhcpcd[2804]: timed out Jul 09 09:46:16 integro sh[2762]: ifup: failed to bring up eth0 Jul 09 09:46:16 integro dhcpcd[2804]: dhcpcd exited Jul 09 09:46:16 integro systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 09 09:46:16 integro systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 09 09:46:16 integro dhcpcd[2838]: timed out Jul 09 09:46:16 integro dhcpcd[2838]: timed out Jul 09 09:46:16 integro dhcpcd[2838]: forked to background, child pid 3385 Jul 09 09:46:16 integro systemd[1]: Started DHCP Client Daemon. Jul 09 09:46:16 integro dhcpcd[2838]: forked to background, child pid 3385 Jul 09 09:46:17 integro volumio[3267]: info: MYVOLUMIO Environment detected Jul 09 09:46:17 integro volumio[3267]: info: Plugin folders cleanup Jul 09 09:46:17 integro volumio[3267]: info: Scanning into folder /volumio/app/plugins/ Jul 09 09:46:17 integro volumio[3267]: info: Scanning category audio_interface Jul 09 09:46:17 integro volumio[3267]: info: Scanning category miscellanea Jul 09 09:46:17 integro volumio[3267]: info: Scanning category music_service Jul 09 09:46:17 integro volumio[3267]: info: Scanning category plugins.json Jul 09 09:46:17 integro volumio[3267]: info: Scanning category system_controller Jul 09 09:46:17 integro volumio[3267]: info: Scanning category user_interface Jul 09 09:46:17 integro volumio[3267]: info: Scanning into folder /data/plugins/ Jul 09 09:46:17 integro volumio[3267]: info: Scanning category music_service Jul 09 09:46:17 integro volumio[3267]: info: Scanning category user_interface Jul 09 09:46:17 integro volumio[3267]: info: Plugin folders cleanup completed Jul 09 09:46:17 integro volumio[3267]: info: ------------------------------------------- Jul 09 09:46:17 integro volumio[3267]: info: ----- Core plugins startup ---- Jul 09 09:46:17 integro volumio[3267]: info: ------------------------------------------- Jul 09 09:46:17 integro volumio[3267]: info: Loading plugins from folder /volumio/app/plugins/ Jul 09 09:46:17 integro volumio[3267]: info: Adding plugin multiroom to MyMusic Plugins Jul 09 09:46:17 integro volumio[3267]: info: Adding plugin upnp to MyMusic Plugins Jul 09 09:46:17 integro volumio[3267]: info: Adding plugin metavolumio to MyMusic Plugins Jul 09 09:46:17 integro volumio[3267]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 09 09:46:17 integro volumio[3267]: info: Adding plugin cd_controller to MyMusic Plugins Jul 09 09:46:17 integro volumio[3267]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 09 09:46:17 integro volumio[3267]: info: Adding plugin raat to MyMusic Plugins Jul 09 09:46:17 integro volumio[3267]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 09 09:46:17 integro volumio[3267]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 09 09:46:17 integro volumio[3267]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 09 09:46:17 integro volumio[3267]: info: Loading plugins from folder /data/plugins/ Jul 09 09:46:17 integro volumio[3267]: info: Loading plugin "system"... Jul 09 09:46:17 integro volumio[3267]: info: Loading plugin "appearance"... Jul 09 09:46:19 integro volumio-remote-updater[2827]: [2025-07-09 09:46:19] [connect] Successful connection Jul 09 09:46:19 integro volumio[3267]: info: Loading plugin "network"... Jul 09 09:46:19 integro volumio[3267]: info: Refreshing Cached IP Addresses Jul 09 09:46:19 integro sudo[3392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 09 09:46:19 integro sudo[3392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:19 integro sudo[3394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 09 09:46:19 integro sudo[3394]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:19 integro sudo[3392]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:19 integro volumio[3267]: info: Loading plugin "services"... Jul 09 09:46:19 integro volumio[3267]: info: Loading plugin "alsa_controller"... Jul 09 09:46:19 integro sudo[3394]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:19 integro sudo[3407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 09 09:46:19 integro sudo[3407]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:19 integro kernel: wl_escan_set_scan: ioctl_ver=2 Jul 09 09:46:19 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 09:46:19 integro volumio[3267]: info: Loading plugin "wizard"... Jul 09 09:46:19 integro volumio[3267]: info: Loading plugin "networkfs"... Jul 09 09:46:19 integro volumio[3267]: info: Starting Udev Watcher for removable devices Jul 09 09:46:19 integro sudo[3432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=sfingesicilia,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.0.172/share_sda1/Music /mnt/NAS/The_Sound Jul 09 09:46:19 integro sudo[3432]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:19 integro kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jul 09 09:46:19 integro kernel: CIFS VFS: cifs_mount failed w/return code = -101 Jul 09 09:46:19 integro sudo[3432]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:19 integro volumio[3267]: info: Mounting Device ISSD Jul 09 09:46:19 integro sudo[3441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/mmcblk1p1 /mnt/INTERNAL/ -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 09 09:46:19 integro sudo[3441]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:19 integro kernel: FAT-fs (mmcblk1p1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jul 09 09:46:19 integro kernel: FAT-fs (mmcblk1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jul 09 09:46:19 integro sudo[3441]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:19 integro volumio[3267]: info: Binding Internal Memory position Jul 09 09:46:19 integro sudo[3444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -o bind /mnt/INTERNAL /data/INTERNAL Jul 09 09:46:19 integro sudo[3444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:19 integro sudo[3444]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:19 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da30000 drv_status 0x0 e_type 69 e_states 8 Jul 09 09:46:19 integro volumio[3267]: info: Ignoring mount for partition: rootfs Jul 09 09:46:19 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da30000 drv_status 0x0 e_type 69 e_states 8 Jul 09 09:46:19 integro volumio[3267]: info: Ignoring mount for partition: BOOT Jul 09 09:46:19 integro volumio[3267]: info: Ignoring mount for partition: volumio Jul 09 09:46:19 integro volumio[3267]: info: Ignoring mount for partition: volumio_data Jul 09 09:46:19 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 09 09:46:19 integro volumio[3267]: info: Loading plugin "volumio_command_line_client"... Jul 09 09:46:19 integro volumio[3267]: info: Loading plugin "upnp"... Jul 09 09:46:19 integro volumio[3267]: info: [1752047179995] Starting Upmpd Daemon Jul 09 09:46:19 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 09 09:46:20 integro volumio[3267]: info: Loading plugin "my_music"... Jul 09 09:46:20 integro volumio[3267]: info: Loading plugin "mpd"... Jul 09 09:46:20 integro volumio[3267]: info: Loading plugin "upnp_browser"... Jul 09 09:46:20 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:21 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:21 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:21 integro volumio[3267]: info: Loading plugin "alarm-clock"... Jul 09 09:46:21 integro sudo[3407]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:21 integro volumio[3267]: info: Loading plugin "manifestui"... Jul 09 09:46:21 integro volumio[3267]: info: Loading plugin "metavolumio"... Jul 09 09:46:23 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da30000 drv_status 0x0 e_type 69 e_states 8 Jul 09 09:46:23 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da30000 drv_status 0x0 e_type 69 e_states 8 Jul 09 09:46:23 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da30000 drv_status 0x0 e_type 69 e_states 0 Jul 09 09:46:23 integro volumio[3267]: info: Loading plugin "airplay_emulation"... Jul 09 09:46:23 integro volumio[3267]: info: Starting Shairport Sync Jul 09 09:46:23 integro volumio[3267]: info: Loading plugin "cd_controller"... Jul 09 09:46:24 integro volumio[3267]: info: Loading plugin "last_100"... Jul 09 09:46:24 integro volumio[3267]: info: Loading plugin "qobuzconnect"... Jul 09 09:46:24 integro volumio[3267]: info: Loading plugin "raat"... Jul 09 09:46:24 integro volumio[3267]: info: RAAT Plugin loaded Jul 09 09:46:24 integro volumio[3267]: info: Adding restartRAATSocket REST API Endpoint Jul 09 09:46:24 integro volumio[3267]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jul 09 09:46:24 integro volumio[3267]: info: Loading plugin "streaming_services"... Jul 09 09:46:26 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:26 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:26 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:27 integro volumio[3267]: info: Starting Streaming Service Transparent Proxy Jul 09 09:46:27 integro volumio[3267]: info: Loading plugin "tidalconnect"... Jul 09 09:46:27 integro systemd[1]: systemd-hostnamed.service: Succeeded. Jul 09 09:46:27 integro volumio[3267]: info: Loading plugin "webradio"... Jul 09 09:46:27 integro volumio[3267]: info: Loading plugin "i2s_dacs"... Jul 09 09:46:27 integro volumio[3267]: info: I2S DAC not set, start Auto-detection Jul 09 09:46:27 integro volumio[3267]: info: Loading plugin "volumiodiscovery"... Jul 09 09:46:27 integro volumio[3267]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 09 09:46:27 integro volumio[3267]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 09 09:46:27 integro volumio[3267]: *** WARNING *** For more information see Jul 09 09:46:27 integro node[3267]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 09 09:46:27 integro volumio[3267]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 09 09:46:27 integro volumio[3267]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 09 09:46:27 integro volumio[3267]: *** WARNING *** For more information see Jul 09 09:46:27 integro node[3267]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 09 09:46:27 integro node[3267]: *** WARNING *** For more information see Jul 09 09:46:27 integro node[3267]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 09 09:46:27 integro node[3267]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 09 09:46:27 integro node[3267]: *** WARNING *** For more information see Jul 09 09:46:27 integro volumio[3267]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 09 09:46:27 integro volumio[3267]: info: Discovery: Started advertising with name: Integro Jul 09 09:46:27 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 09 09:46:27 integro volumio[3267]: info: Loading plugin "bandcamp"... Jul 09 09:46:28 integro volumio[3267]: STREAMING PROXY: Starting server on port 3245 Jul 09 09:46:28 integro volumio[3267]: Node JS runtime: 14 Jul 09 09:46:29 integro volumio[3267]: info: Loading plugin "spop"... Jul 09 09:46:30 integro kernel: fb: mem_free_work, free memory: addr:800000 Jul 09 09:46:31 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:31 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:31 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:31 integro volumio[3267]: info: Loading plugin "multiroom"... Jul 09 09:46:33 integro volumio[3267]: info: Applying required configuration parameters for plugin multiroom Jul 09 09:46:33 integro sudo[3651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 09 09:46:33 integro sudo[3651]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:33 integro sudo[3651]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:33 integro volumio[3267]: info: MRS: MultiRoom plugin initialized Jul 09 09:46:33 integro volumio[3267]: info: MRS: STOPPING SNAPCLIENT Jul 09 09:46:33 integro volumio[3267]: info: MRS: Snap server stop Jul 09 09:46:33 integro volumio[3267]: info: MRS: STOPPING volumioStreaming Jul 09 09:46:33 integro sudo[3668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 09 09:46:33 integro sudo[3669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 09 09:46:33 integro sudo[3668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:33 integro sudo[3669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:33 integro volumio[3267]: info: Loading plugin "outputs"... Jul 09 09:46:33 integro volumio[3267]: info: Loading plugin "albumart"... Jul 09 09:46:33 integro sudo[3671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 09 09:46:33 integro sudo[3671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:33 integro sudo[3675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 09 09:46:33 integro sudo[3675]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:33 integro sudo[3675]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:33 integro sudo[3668]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:33 integro volumio[3267]: info: Plugin example_plugin is not enabled Jul 09 09:46:33 integro volumio[3267]: info: Loading plugin "hdmi_arc"... Jul 09 09:46:33 integro sudo[3669]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:33 integro sudo[3671]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:34 integro volumio-remote-updater[2827]: [2025-07-09 09:46:34] [connect] Successful connection Jul 09 09:46:34 integro volumio[3267]: info: Loading plugin "hi_res_audio"... Jul 09 09:46:34 integro volumio[3267]: Forking 3 albumart workers Jul 09 09:46:35 integro volumio[3267]: Starting albumart workers Jul 09 09:46:35 integro volumio[3267]: Starting albumart workers Jul 09 09:46:35 integro volumio[3267]: Starting albumart workers Jul 09 09:46:36 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:36 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:36 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:36 integro volumio[3267]: info: Applying required configuration parameters for plugin hi_res_audio Jul 09 09:46:36 integro volumio[3267]: info: Loading plugin "inputs"... Jul 09 09:46:37 integro volumio[3267]: info: Loading plugin "qobuz"... Jul 09 09:46:39 integro volumio[3267]: info: Loading plugin "smart_inputs"... Jul 09 09:46:39 integro volumio[3267]: info: Loading plugin "tidal"... Jul 09 09:46:41 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:41 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:41 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:41 integro volumio[3267]: info: Loading plugin "integrocontrol"... Jul 09 09:46:41 integro volumio[3267]: info: Adding this device properties Jul 09 09:46:41 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jul 09 09:46:41 integro volumio[3267]: info: Setting Additional Device Volumio Properties: [object Object] Jul 09 09:46:41 integro volumio[3267]: info: Loading plugin "updater_comm"... Jul 09 09:46:41 integro volumio[3267]: info: Plugin mpdemulation is not enabled Jul 09 09:46:41 integro volumio[3267]: info: Loading plugin "rest_api"... Jul 09 09:46:41 integro volumio[3267]: info: Loading plugin "websocket"... Jul 09 09:46:41 integro volumio[3267]: info: Starting Socket.io Server version 2.3.0 Jul 09 09:46:41 integro volumio[3267]: info: Loading plugin "randomizer"... Jul 09 09:46:42 integro volumio[3267]: info: Applying required configuration parameters for plugin randomizer Jul 09 09:46:42 integro volumio[3267]: info: Loading i18n strings for locale it Jul 09 09:46:42 integro volumio[3267]: Updating browse sources language Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::initPlayerControls Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 09:46:42 integro volumio[3267]: Express server listening on port 3000 Jul 09 09:46:42 integro volumio[3267]: [Metrics] WebUI: 27s 778.33ms Jul 09 09:46:42 integro volumio[3267]: info: CoreStateMachine::resetVolumioState Jul 09 09:46:42 integro volumio[3267]: info: CoreStateMachine::getcurrentVolume Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::volumioRetrievevolume Jul 09 09:46:42 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:46:42 integro sudo[3767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 09 09:46:42 integro sudo[3767]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:42 integro sudo[3767]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:42 integro sudo[3770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 09 09:46:42 integro volumio[3267]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 09 09:46:42 integro sudo[3770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:42 integro volumio[3267]: info: Completed loading Core Plugins Jul 09 09:46:42 integro volumio[3267]: info: Preparing to generate the ALSA configuration file Jul 09 09:46:42 integro sudo[3770]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:42 integro volumio[3267]: info: Volumio Network Manager: Network status updated: 0 Jul 09 09:46:42 integro volumio[3267]: Unable to parse: Jul 09 09:46:42 integro volumio[3267]: Simple mixer control 'Audio hdmi-out mute',0 Jul 09 09:46:42 integro volumio[3267]: Capabilities: pswitch pswitch-joined Jul 09 09:46:42 integro volumio[3267]: Playback channels: Mono Jul 09 09:46:42 integro volumio[3267]: Mono: Playback [on] Jul 09 09:46:42 integro volumio[3267]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 09 09:46:43 integro volumio[3267]: info: MRS: Removed streaming files Jul 09 09:46:43 integro volumio[3267]: info: MRS: volumioStreaming STOPPED Jul 09 09:46:43 integro volumio[3267]: info: MRS: SNAPSERVER STOPPED Jul 09 09:46:43 integro volumio[3267]: info: MRS: SNAPCLIENT STOPPED Jul 09 09:46:43 integro volumio[3267]: info: Cannot mount NAS The_Sound at system boot, trial number 1 ,retrying in 5 seconds Jul 09 09:46:43 integro volumio[3267]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 09 09:46:43 integro volumio[3267]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 09 09:46:43 integro volumio[3267]: info: Reading ALSA contributions from plugins. Jul 09 09:46:43 integro volumio[3267]: Unable to parse: Jul 09 09:46:43 integro volumio[3267]: Simple mixer control 'Audio hdmi-out mute',0 Jul 09 09:46:43 integro volumio[3267]: Capabilities: pswitch pswitch-joined Jul 09 09:46:43 integro volumio[3267]: Playback channels: Mono Jul 09 09:46:43 integro volumio[3267]: Mono: Playback [on] Jul 09 09:46:43 integro volumio[3267]: info: VolumeController:: Volume=undefined Mute =false Jul 09 09:46:43 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:43 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:43 integro volumio[3267]: info: CoreStateMachine::updateTrackBlock Jul 09 09:46:43 integro volumio[3267]: info: CorePlayQueue::getTrackBlock Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::volumioRetrievevolume Jul 09 09:46:43 integro volumio[3267]: info: Reloading queue from file Jul 09 09:46:43 integro volumio[3267]: Unable to parse: Jul 09 09:46:43 integro volumio[3267]: Simple mixer control 'Audio hdmi-out mute',0 Jul 09 09:46:43 integro volumio[3267]: Capabilities: pswitch pswitch-joined Jul 09 09:46:43 integro volumio[3267]: Playback channels: Mono Jul 09 09:46:43 integro volumio[3267]: Mono: Playback [on] Jul 09 09:46:43 integro volumio[3267]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 09 09:46:43 integro volumio[3267]: info: CoreStateMachine::setRepeat null single undefined Jul 09 09:46:43 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:43 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:43 integro volumio[3267]: info: CoreStateMachine::setRandom null Jul 09 09:46:43 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:43 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:43 integro volumio[3267]: Unable to parse: Jul 09 09:46:43 integro volumio[3267]: Simple mixer control 'Audio hdmi-out mute',0 Jul 09 09:46:43 integro volumio[3267]: Capabilities: pswitch pswitch-joined Jul 09 09:46:43 integro volumio[3267]: Playback channels: Mono Jul 09 09:46:43 integro volumio[3267]: Mono: Playback [on] Jul 09 09:46:43 integro volumio[3267]: info: VolumeController:: Volume=undefined Mute =false Jul 09 09:46:43 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:43 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:43 integro volumio[3267]: info: Asound.conf file unchanged, so no further update is needed Jul 09 09:46:43 integro sudo[3783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 09 09:46:43 integro volumio[3267]: info: Output device has changed, restarting MPD Jul 09 09:46:43 integro sudo[3783]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:43 integro systemd[1]: Started UPnP Renderer front-end to MPD. Jul 09 09:46:43 integro sudo[3783]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:43 integro sudo[3786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 09 09:46:43 integro sudo[3786]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:43 integro volumio[3267]: info: Output device has changed, restarting Shairport Sync Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:46:43 integro sudo[3786]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:43 integro sudo[3796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 09 09:46:43 integro sudo[3796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:43 integro volumio[3267]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 09 09:46:43 integro volumio[3267]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:43 integro systemd[1]: Stopping Music Player Daemon... Jul 09 09:46:43 integro volumio[3267]: info: ___________ START PLUGINS ___________ Jul 09 09:46:43 integro volumio[3267]: info: ControllerMpd::onStart: Initializing MPD Jul 09 09:46:43 integro volumio[3267]: info: Creating MPD Configuration file Jul 09 09:46:43 integro kernel: aml_tdm_open Jul 09 09:46:43 integro kernel: Not init audio effects Jul 09 09:46:43 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 09 09:46:43 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jul 09 09:46:43 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 09:46:43 integro sudo[3805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 09 09:46:43 integro sudo[3805]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:43 integro volumio[3267]: info: [1752047203899] CoreMusicLibrary::Adding element Server multimediali Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:46:43 integro sudo[3805]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:43 integro volumio[3267]: info: Adding METAVOLUMIO REST API Endpoints Jul 09 09:46:43 integro volumio[3267]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 09 09:46:43 integro volumio[3267]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 09 09:46:43 integro volumio[3267]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 09 09:46:43 integro volumio[3267]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 09 09:46:43 integro sudo[3808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:46:43 integro sudo[3808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:43 integro systemd[1]: mpd.service: Succeeded. Jul 09 09:46:43 integro systemd[1]: Stopped Music Player Daemon. Jul 09 09:46:43 integro systemd[1]: Starting Music Player Daemon... Jul 09 09:46:43 integro volumio[3267]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:43 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:46:44 integro systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 09 09:46:44 integro systemd[1]: mpd.service: Succeeded. Jul 09 09:46:44 integro systemd[1]: Stopped Music Player Daemon. Jul 09 09:46:44 integro systemd[1]: Starting Music Player Daemon... Jul 09 09:46:44 integro volumio[3267]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 09 09:46:44 integro volumio[3267]: info: Preparing CD Folders Jul 09 09:46:44 integro volumio[3267]: info: Adding CD REST API Endpoints Jul 09 09:46:44 integro volumio[3267]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 09 09:46:44 integro volumio[3267]: info: Starting UDEV Watcher for CD Jul 09 09:46:44 integro volumio[3267]: info: Detecting CD presence with UDEV Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 09:46:44 integro volumio[3267]: info: [1752047204116] CoreMusicLibrary::Adding element Last_100 Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:46:44 integro volumio[3267]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jul 09 09:46:44 integro volumio[3267]: info: QobuzConnect: Starting Qobuz Connect socket and service Jul 09 09:46:44 integro volumio[3267]: info: Starting RAAT Plugin Jul 09 09:46:44 integro sudo[3815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 09 09:46:44 integro sudo[3815]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jul 09 09:46:44 integro volumio[3267]: info: Additional UI Settings Added for plugin music_service/raat Jul 09 09:46:44 integro volumio[3267]: info: Registering DSP Elements listener and retrieving current ones Jul 09 09:46:44 integro volumio[3267]: info: Additional DSP elements updated Jul 09 09:46:44 integro sudo[3822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:44 integro sudo[3822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:44 integro sudo[3815]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:44 integro volumio[3267]: info: Updating RAAT Signal Path Jul 09 09:46:44 integro volumio[3267]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 09 09:46:44 integro volumio[3267]: info: Streaming services startup Jul 09 09:46:44 integro volumio[3267]: info: Starting Streaming Daemon Jul 09 09:46:44 integro sudo[3822]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:44 integro sudo[3829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 09 09:46:44 integro sudo[3829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:44 integro sudo[3830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 09 09:46:44 integro sudo[3830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:44 integro sudo[3830]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:44 integro systemd[1]: Started Volumio Qobuz Connect Service. Jul 09 09:46:44 integro sudo[3829]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 09:46:44 integro volumio[3267]: info: [1752047204472] CoreMusicLibrary::Adding element Webradio Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 09:46:44 integro volumio[3267]: info: Initializing BBC Radios Jul 09 09:46:44 integro sudo[3844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 09 09:46:44 integro sudo[3844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:44 integro sudo[3844]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 09:46:44 integro volumio[3267]: info: [1752047204669] CoreMusicLibrary::Adding element Bandcamp Discover Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:46:44 integro volumio[3267]: Cannot find translation for source Bandcamp Discover Jul 09 09:46:44 integro volumio[3267]: info: Creating Spotify config file Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 09 09:46:44 integro volumio[3267]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:44 integro volumio[3267]: info: Starting CEC Infrastructure Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:46:44 integro kernel: aml_tdm_open Jul 09 09:46:44 integro kernel: Not init audio effects Jul 09 09:46:44 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 09 09:46:44 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jul 09 09:46:44 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 09 09:46:44 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jul 09 09:46:44 integro volumio[3267]: info: Additional UI Settings Added for plugin music_service/hdmi_arc Jul 09 09:46:44 integro volumio[3267]: error: Hi Res Audio Failed Login: Missing Login Data Jul 09 09:46:44 integro volumio[3267]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 09 09:46:44 integro volumio[3267]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 09 09:46:44 integro volumio[3267]: info: Initializing Serial Communication on port /dev/ttyS3 Jul 09 09:46:44 integro volumio[3267]: info: Touch Event Listener Process Starting Jul 09 09:46:44 integro kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Jul 09 09:46:44 integro kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Jul 09 09:46:44 integro kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Jul 09 09:46:45 integro volumio[3267]: info: Refreshing QOBUZ token Jul 09 09:46:45 integro volumio[3267]: info: Adding inputs REST Endpoints Jul 09 09:46:45 integro volumio[3267]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jul 09 09:46:45 integro volumio[3267]: info: Scanning Audio Inputs Jul 09 09:46:45 integro sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Jul 09 09:46:45 integro sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:45 integro volumio[3267]: info: Checking against Known Cards name Jul 09 09:46:45 integro volumio[3267]: info: Checking against Known Cards name Jul 09 09:46:45 integro volumio[3267]: info: Checking against Known Cards name Jul 09 09:46:45 integro volumio[3267]: info: Checking against Known Cards name Jul 09 09:46:45 integro volumio[3267]: info: Checking against Known Cards name Jul 09 09:46:45 integro volumio[3267]: info: Adding Server instance for streaming Jul 09 09:46:45 integro sudo[3873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Jul 09 09:46:45 integro sudo[3873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:45 integro sudo[3873]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:45 integro sudo[3876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Jul 09 09:46:45 integro sudo[3876]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:45 integro sudo[3876]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:45 integro sudo[3879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Jul 09 09:46:45 integro sudo[3879]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:45 integro sudo[3879]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:45 integro sudo[3883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Jul 09 09:46:45 integro sudo[3883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:45 integro sudo[3883]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:45 integro volumio[3267]: info: MP1 Muting HDMI Output Jul 09 09:46:45 integro volumio[3267]: info: Apply VIM3L Onboard LEDs Settings Jul 09 09:46:45 integro volumio[3267]: info: Turning On Onboard LEDs Jul 09 09:46:45 integro sudo[3908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jul 09 09:46:45 integro sudo[3908]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:45 integro sudo[3908]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:45 integro volumio[3267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 09:46:45 integro volumio[3267]: info: [1752047205927] CoreMusicLibrary::Adding element Randomizer Jul 09 09:46:45 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:46:45 integro volumio[3267]: Cannot find translation for source Bandcamp Discover Jul 09 09:46:45 integro volumio[3267]: Cannot find translation for source Randomizer Jul 09 09:46:45 integro sudo[3917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jul 09 09:46:45 integro sudo[3917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:45 integro volumio[3267]: info: Volumio Calling Home Jul 09 09:46:45 integro sudo[3917]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:46 integro volumio[3267]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jul 09 09:46:46 integro volumio[3267]: (node:3267) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jul 09 09:46:46 integro volumio[3267]: (Use `node --trace-deprecation ...` to show where the warning was created) Jul 09 09:46:46 integro volumio[3267]: info: Stopping AccessToken refresher cron Jul 09 09:46:46 integro volumio[3267]: info: AccessToken refresher cron started Jul 09 09:46:46 integro volumio[3267]: info: Adding TIDAL REST API Endpoints Jul 09 09:46:46 integro volumio[3267]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 09 09:46:46 integro volumio[3267]: Unhandled rejection Error: No sockets available, cannot start. Jul 09 09:46:46 integro volumio[3267]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 09 09:46:46 integro volumio[3267]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 09 09:46:46 integro volumio[3267]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 09 09:46:46 integro volumio[3267]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 09 09:46:46 integro volumio[3267]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 09 09:46:46 integro volumio[3267]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 09 09:46:46 integro volumio[3267]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 09 09:46:46 integro volumio[3267]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 09 09:46:46 integro volumio[3267]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 09 09:46:46 integro volumio[3267]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 09 09:46:46 integro volumio[3267]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 09 09:46:46 integro volumio[3267]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 09 09:46:46 integro volumio[3267]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 09 09:46:46 integro volumio[3267]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 09 09:46:46 integro volumio[3267]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 09 09:46:46 integro volumio[3267]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 09 09:46:46 integro volumio[3267]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Jul 09 09:46:46 integro volumio[3267]: info: Serial port opened successfully Jul 09 09:46:46 integro volumio[3267]: info: Sending serial start messages Jul 09 09:46:46 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:46 integro volumio[3267]: info: Reporting MCU Network Status: 0 Jul 09 09:46:46 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:46 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:46 integro volumio[3267]: warn: QOBUZ login failed: No access token received Jul 09 09:46:46 integro volumio[3267]: info: Stopping AccessToken refresher cron for QOBUZ Jul 09 09:46:46 integro volumio[3267]: info: AccessToken refresher cron started for QOBUZ Jul 09 09:46:46 integro volumio[3267]: info: Adding QOBUZ REST API Endpoints Jul 09 09:46:46 integro volumio[3267]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 09 09:46:46 integro volumio[3267]: error: Cannot start Volumio Streaming Daemon Jul 09 09:46:46 integro volumio[3267]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 09 09:46:46 integro volumio[3267]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 09 09:46:46 integro volumio[3267]: info: RAAT Albumart path created successfully Jul 09 09:46:46 integro volumio[3267]: info: MPD Permissions set Jul 09 09:46:46 integro volumio[3267]: info: MPD Permissions set Jul 09 09:46:46 integro volumio[3267]: info: Upmpdcli Daemon Started Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jul 09 09:46:46 integro volumio[3267]: info: Setting Device Volume Override Jul 09 09:46:46 integro volumio[3267]: info: Applying Volume Override Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 09 09:46:46 integro volumio[3267]: info: Updating Volume Controller Parameters: Device: 0,0 Name: I2S Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 09 09:46:46 integro volumio[3267]: info: Enabling external Volume Control Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:46 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:46 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:46 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:46 integro volumio[3267]: info: Executing endpoint qc_getconfig Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 09 09:46:46 integro qobuz-connect[3839]: 20250709 09:46:46.634 [3839.3839] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 09 09:46:46 integro volumio[3267]: info: Spotify config file written Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 09 09:46:46 integro volumio[3267]: info: Setting Additional System Software info: Hardware Revision: 1.2 Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 09 09:46:46 integro volumio[3267]: info: Setting HW Firmware info: undefined Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 09 09:46:46 integro volumio[3267]: info: Setting HW Version info: 1.2 Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 09 09:46:46 integro volumio[3267]: info: Setting Additional System Software info: Hardware Revision: 1.2, Firmware Version: 0.7.2 Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 09 09:46:46 integro volumio[3267]: info: Setting HW Firmware info: 0.7.2 Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 09 09:46:46 integro volumio[3267]: info: Setting HW Version info: 1.2 Jul 09 09:46:46 integro volumio[3267]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 09 09:46:46 integro volumio[3267]: info: MCU Signalled Headphone Mode Disabled Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 09 09:46:46 integro volumio[3267]: info: Reporting Headphone State: false Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:46 integro volumio[3267]: info: Updating RAAT Signal Path Jul 09 09:46:46 integro volumio[3267]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 09 09:46:46 integro volumio[3267]: info: MCU Signalled Sleep Mode Active Jul 09 09:46:46 integro sudo[3943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 09 09:46:46 integro sudo[3943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:46 integro volumio[3267]: info: Enabling Advanced system settings configuration Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jul 09 09:46:46 integro volumio[3267]: info: Additional UI Settings Added for plugin music_service/inputs Jul 09 09:46:46 integro volumio[3267]: info: MCU Signalled Auto Boot Mode On Power Disabled Jul 09 09:46:46 integro qobuz-connect[3839]: 20250709 09:46:46.787 [3839.3839] INFO VolumeManager: [0xaca7f8f0]: Setting new playback volume: 75 Jul 09 09:46:46 integro qobuz-connect[3839]: 20250709 09:46:46.787 [3839.3839] INFO VolumeManager: [0xaca7f8f0]: Setting new mute state: 0 Jul 09 09:46:46 integro qobuz-connect[3839]: 20250709 09:46:46.787 [3839.3839] INFO QobuzConnect: [0xaca802c0]: Client initialized! Jul 09 09:46:46 integro qobuz-connect[3839]: 20250709 09:46:46.787 [3839.3839] INFO SampleApp: Starting Avahi advertising, name: Integro, service name: _qobuz-connect._tcp Jul 09 09:46:46 integro systemd[1]: Started go-librespot Daemon. Jul 09 09:46:46 integro sudo[3943]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:46 integro sudo[3946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force off Jul 09 09:46:46 integro sudo[3946]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:46 integro qobuz-connect[3839]: 20250709 09:46:46.858 [3839.3839] INFO LocalConfigManager: [0xaca7f1d0]: Starting Local Configuration server Jul 09 09:46:46 integro qobuz-connect[3839]: 20250709 09:46:46.859 [3839.3839] INFO SampleApp: Starting Local configuration server Jul 09 09:46:46 integro qobuz-connect[3839]: 20250709 09:46:46.859 [3839.3839] INFO SampleApp: Playback volume changed: 75 Jul 09 09:46:46 integro qobuz-connect[3839]: 20250709 09:46:46.860 [3839.3839] INFO SampleApp: Connected to UNIX socket client 0xaca74ed8 Jul 09 09:46:46 integro go-librespot[3947]: go-librespot daemon starting... Jul 09 09:46:46 integro volumio[3267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 09 09:46:46 integro volumio[3267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 09 09:46:46 integro volumio[3267]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 09 09:46:46 integro volumio[3267]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:46:46 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 09 09:46:46 integro kernel: fb: osd[0] enable: 1 (Xorg) Jul 09 09:46:46 integro kernel: fb: osd[0] enable: 0 (Xorg) Jul 09 09:46:46 integro kernel: fb: osd[0] enable: 0 (Xorg) Jul 09 09:46:46 integro sudo[3946]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:47 integro go-librespot[3947]: time="2025-07-09T09:46:47+02:00" level=info msg="running go-librespot 0.2.0" Jul 09 09:46:47 integro go-librespot[3947]: time="2025-07-09T09:46:47+02:00" level=debug msg="app state loaded" Jul 09 09:46:47 integro go-librespot[3947]: time="2025-07-09T09:46:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 09:46:47 integro go-librespot[3947]: time="2025-07-09T09:46:47+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" Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 09 09:46:47 integro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 09 09:46:47 integro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:46:47 integro volumio[3267]: info: No need to fix Spotify hosts Jul 09 09:46:47 integro volumio[3267]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jul 09 09:46:47 integro volumio[3267]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jul 09 09:46:47 integro volumio[3267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:47 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:47 integro mpd[3833]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 09 09:46:47 integro mpd[3833]: output: No 'audio_output' defined in config file Jul 09 09:46:47 integro kernel: aml_tdm_open Jul 09 09:46:47 integro kernel: Not init audio effects Jul 09 09:46:47 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 09 09:46:47 integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 09 09:46:47 integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 09 09:46:47 integro kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Jul 09 09:46:47 integro kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d292a18, id(1), clksel(1) Jul 09 09:46:47 integro kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 09 09:46:47 integro kernel: dump_pcm_setting(ffffffc03d292a18) Jul 09 09:46:47 integro kernel: pcm_mode(1) Jul 09 09:46:47 integro kernel: sysclk(12288000) Jul 09 09:46:47 integro kernel: sysclk_bclk_ratio(4) Jul 09 09:46:47 integro kernel: bclk(3072000) Jul 09 09:46:47 integro kernel: bclk_lrclk_ratio(64) Jul 09 09:46:47 integro kernel: lrclk(48000) Jul 09 09:46:47 integro kernel: tx_mask(0x3) Jul 09 09:46:47 integro kernel: rx_mask(0x3) Jul 09 09:46:47 integro kernel: slots(2) Jul 09 09:46:47 integro kernel: slot_width(32) Jul 09 09:46:47 integro kernel: lane_mask_in(0x2) Jul 09 09:46:47 integro kernel: lane_mask_out(0x1) Jul 09 09:46:47 integro kernel: lane_oe_mask_in(0x0) Jul 09 09:46:47 integro kernel: lane_oe_mask_out(0x0) Jul 09 09:46:47 integro kernel: lane_lb_mask_in(0x0) Jul 09 09:46:47 integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 09 09:46:47 integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 09 09:46:47 integro kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Jul 09 09:46:47 integro kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 09 09:46:47 integro kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 09 09:46:47 integro kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 09 09:46:47 integro kernel: aml_tdm_prepare(), reset fddr Jul 09 09:46:47 integro kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 09 09:46:47 integro kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Jul 09 09:46:47 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 09 09:46:47 integro kernel: tdm playback mute: 0, lane_cnt = 8 Jul 09 09:46:47 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 09 09:46:47 integro kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 09 09:46:47 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jul 09 09:46:47 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 09 09:46:47 integro mpd[3833]: output: Successfully detected a sndio audio device Jul 09 09:46:47 integro mpd[3833]: zeroconf: No global port, disabling zeroconf Jul 09 09:46:47 integro systemd[1]: Started Music Player Daemon. Jul 09 09:46:47 integro sudo[3808]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:47 integro sudo[3796]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:47 integro volumio[3267]: info: Enabling Screen Saver via dpms Jul 09 09:46:47 integro volumio[3267]: Upnp client error: Error: This socket has been ended by the other party Jul 09 09:46:47 integro volumio[3267]: info: Starting Shairport Sync Jul 09 09:46:47 integro volumio[3267]: info: Starting Shairport Sync Jul 09 09:46:47 integro volumio[3267]: info: Starting Shairport Sync Jul 09 09:46:47 integro sudo[3975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 09 09:46:47 integro sudo[3975]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:47 integro sudo[3977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 09 09:46:47 integro sudo[3977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:47 integro systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 09 09:46:47 integro volumio[3267]: info: Adding Inputs via Serial API Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 09 09:46:47 integro volumio[3267]: info: [1752047207462] CoreMusicLibrary::Adding element Inputs Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:46:47 integro volumio[3267]: Cannot find translation for source Bandcamp Discover Jul 09 09:46:47 integro volumio[3267]: Cannot find translation for source Randomizer Jul 09 09:46:47 integro systemd[1]: shairport-sync.service: Succeeded. Jul 09 09:46:47 integro systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 09 09:46:47 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:47 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:47 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:47 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:47 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:47 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:47 integro sudo[3981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 09 09:46:47 integro sudo[3981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:47 integro systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 09 09:46:47 integro systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 09 09:46:47 integro sudo[3975]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:47 integro systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 09 09:46:47 integro systemd[1]: shairport-sync.service: Succeeded. Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:47 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:47 integro systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 09 09:46:47 integro systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 09 09:46:47 integro sudo[3977]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:47 integro systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 09 09:46:47 integro systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 09 09:46:47 integro systemd[1]: shairport-sync.service: Succeeded. Jul 09 09:46:47 integro systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 09 09:46:47 integro systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 09 09:46:47 integro sudo[3981]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:47 integro volumio[3267]: error: MPD error: The expression evaluated to a falsy value: Jul 09 09:46:47 integro volumio[3267]: assert.ok(self.idling) Jul 09 09:46:47 integro volumio[3267]: error: The expression evaluated to a falsy value: Jul 09 09:46:47 integro volumio[3267]: assert.ok(self.idling) Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:47 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:47 integro volumio[3267]: info: Shairport-Sync Started Jul 09 09:46:47 integro volumio[3267]: Error adding Membership: Error: addMembership EINVAL Jul 09 09:46:47 integro volumio[3267]: info: MPD running with PID3833 Jul 09 09:46:47 integro volumio[3267]: ,establishing connection Jul 09 09:46:47 integro volumio[3267]: error: updateQueue error: null Jul 09 09:46:47 integro volumio[3267]: info: Shairport-Sync Started Jul 09 09:46:47 integro volumio[3267]: info: TidalConnect service stoped! Jul 09 09:46:47 integro volumio[3267]: info: Shairport-Sync Started Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::servicePushState Jul 09 09:46:47 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:47 integro volumio[3267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received inputs Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jul 09 09:46:47 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:46:47 integro volumio[3267]: Cannot find translation for source Bandcamp Discover Jul 09 09:46:47 integro volumio[3267]: Cannot find translation for source Randomizer Jul 09 09:46:47 integro volumio[3267]: error: updateQueue error: null Jul 09 09:46:48 integro volumio[3267]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 09 09:46:48 integro volumio[3267]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 09 09:46:48 integro sudo[3998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 09 09:46:48 integro sudo[3998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:48 integro sudo[4001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=sfingesicilia,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.0.172/share_sda1/Music /mnt/NAS/The_Sound Jul 09 09:46:48 integro sudo[4001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:48 integro systemd[1]: Started Volumio Tidal Connect Service. Jul 09 09:46:48 integro sudo[3998]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:48 integro kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jul 09 09:46:48 integro kernel: CIFS VFS: cifs_mount failed w/return code = -101 Jul 09 09:46:48 integro sudo[4001]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:48 integro volumio[3267]: info: Cannot mount NAS The_Sound at system boot, trial number 2 ,retrying in 5 seconds Jul 09 09:46:48 integro volumio[3267]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 2 Jul 09 09:46:48 integro volumio[3267]: info: Turning Off Onboard LEDs Jul 09 09:46:48 integro sudo[4022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jul 09 09:46:48 integro sudo[4022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:48 integro sudo[4022]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:48 integro sudo[4025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jul 09 09:46:48 integro sudo[4025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:48 integro sudo[4025]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:48 integro volumio[3267]: info: Onboard LEDs initialized Jul 09 09:46:48 integro volumio[3267]: info: Executing endpoint tc_getconfig Jul 09 09:46:48 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 09 09:46:48 integro vtcs[4005]: STARTING TidalConnect services, version: 1.5.1-beta1 Jul 09 09:46:48 integro vtcs[4005]: STARTED TidalConnect services. Jul 09 09:46:48 integro volumio[3267]: info: Executing endpoint tc_connect Jul 09 09:46:48 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 09 09:46:48 integro volumio[3267]: info: Connecting to TidalConnect Jul 09 09:46:48 integro volumio[3267]: info: CoreCommandRouter::servicePushState Jul 09 09:46:48 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:48 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:48 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:48 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:48 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:48 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:48 integro volumio[3267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jul 09 09:46:48 integro volumio[3267]: info: CoreCommandRouter::servicePushState Jul 09 09:46:48 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:48 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:48 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:48 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:48 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:48 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:48 integro volumio[3267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jul 09 09:46:48 integro volumio[3267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 09 09:46:48 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:48 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:48 integro volumio[3267]: info: Exiting sleep status due to volume change Jul 09 09:46:48 integro volumio[3267]: info: Initializing I2S Bus Jul 09 09:46:48 integro volumio[3267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 09 09:46:48 integro kernel: aml_tdm_open Jul 09 09:46:48 integro kernel: Not init audio effects Jul 09 09:46:48 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 09 09:46:48 integro volumio[3267]: info: MCU Signalled Playback Inactive Jul 09 09:46:48 integro kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 09 09:46:48 integro kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d292a18, id(1), clksel(1) Jul 09 09:46:48 integro kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 09 09:46:48 integro kernel: dump_pcm_setting(ffffffc03d292a18) Jul 09 09:46:48 integro kernel: pcm_mode(1) Jul 09 09:46:48 integro kernel: sysclk(11289600) Jul 09 09:46:48 integro kernel: sysclk_bclk_ratio(4) Jul 09 09:46:48 integro kernel: bclk(2822400) Jul 09 09:46:48 integro kernel: bclk_lrclk_ratio(64) Jul 09 09:46:48 integro kernel: lrclk(44100) Jul 09 09:46:48 integro kernel: tx_mask(0x3) Jul 09 09:46:48 integro kernel: rx_mask(0x3) Jul 09 09:46:48 integro kernel: slots(2) Jul 09 09:46:48 integro kernel: slot_width(32) Jul 09 09:46:48 integro kernel: lane_mask_in(0x2) Jul 09 09:46:48 integro kernel: lane_mask_out(0x1) Jul 09 09:46:48 integro kernel: lane_oe_mask_in(0x0) Jul 09 09:46:48 integro kernel: lane_oe_mask_out(0x0) Jul 09 09:46:48 integro kernel: lane_lb_mask_in(0x0) Jul 09 09:46:48 integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 09 09:46:48 integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 09 09:46:48 integro kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 09 09:46:48 integro kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 09 09:46:48 integro kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 09 09:46:48 integro kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 09 09:46:48 integro kernel: aml_tdm_prepare(), reset fddr Jul 09 09:46:48 integro kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 09 09:46:48 integro kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 09 09:46:48 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 09 09:46:48 integro kernel: tdm playback mute: 0, lane_cnt = 8 Jul 09 09:46:48 integro kernel: aml_tdm_prepare(), reset fddr Jul 09 09:46:48 integro kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 09 09:46:48 integro kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 09 09:46:48 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 09 09:46:48 integro kernel: tdm playback mute: 0, lane_cnt = 8 Jul 09 09:46:49 integro kernel: asoc-aml-card auge_sound: tdm playback enable Jul 09 09:46:49 integro kernel: spdif_a is set to enable Jul 09 09:46:49 integro volumio[3267]: info: Setting up CEC listeners Jul 09 09:46:49 integro volumio[3267]: info: CEC Infrastructure started successfully Jul 09 09:46:49 integro volumio-remote-updater[2827]: [2025-07-09 09:46:49] [connect] Successful connection Jul 09 09:46:49 integro volumio-remote-updater[2827]: [2025-07-09 09:46:49] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752047209 101 Jul 09 09:46:49 integro volumio[3267]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Jul 09 09:46:49 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 09:46:49 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:49 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:49 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:49 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:49 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:49 integro volumio[3267]: info: CoreCommandRouter::servicePushState Jul 09 09:46:49 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:49 integro volumio[3267]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received inputs Jul 09 09:46:49 integro volumio[3267]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jul 09 09:46:49 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:46:49 integro volumio[3267]: Cannot find translation for source Bandcamp Discover Jul 09 09:46:49 integro volumio[3267]: Cannot find translation for source Randomizer Jul 09 09:46:49 integro volumio[3267]: info: CoreStateMachine::pushState Jul 09 09:46:49 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:49 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 09:46:49 integro volumio[3267]: info: CoreCommandRouter::volumioPushState Jul 09 09:46:49 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:49 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:49 integro volumio[3267]: info: MCU Signalled Sleep Mode Disabled Jul 09 09:46:49 integro sudo[4048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jul 09 09:46:49 integro sudo[4048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:49 integro kernel: fb: osd[0] enable: 1 (Xorg) Jul 09 09:46:49 integro kernel: fb: osd[0] enable: 1 (Xorg) Jul 09 09:46:49 integro sudo[4048]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:49 integro sudo[4051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jul 09 09:46:49 integro sudo[4051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:49 integro sudo[4051]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:49 integro kernel: asoc-aml-card auge_sound: tdm playback stop Jul 09 09:46:49 integro kernel: spdif_a is set to disable Jul 09 09:46:49 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 09 09:46:49 integro kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 09 09:46:49 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jul 09 09:46:49 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 09 09:46:49 integro volumio[3267]: info: Successfully initialized Integro I2S Bus Jul 09 09:46:49 integro volumio[3267]: info: MRS: Getting audio outputs on start Jul 09 09:46:49 integro volumio[3267]: info: MRS: Requesting all other devices output Jul 09 09:46:50 integro volumio[3267]: info: go-librespot daemon successfully initialized Jul 09 09:46:50 integro volumio[3267]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 6 Jul 09 09:46:50 integro systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 09 09:46:50 integro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jul 09 09:46:50 integro systemd[1]: Stopped go-librespot Daemon. Jul 09 09:46:50 integro systemd[1]: Started go-librespot Daemon. Jul 09 09:46:50 integro go-librespot[4070]: go-librespot daemon starting... Jul 09 09:46:50 integro go-librespot[4070]: time="2025-07-09T09:46:50+02:00" level=info msg="running go-librespot 0.2.0" Jul 09 09:46:50 integro go-librespot[4070]: time="2025-07-09T09:46:50+02:00" level=debug msg="app state loaded" Jul 09 09:46:50 integro go-librespot[4070]: time="2025-07-09T09:46:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 09:46:50 integro go-librespot[4070]: time="2025-07-09T09:46:50+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" Jul 09 09:46:50 integro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 09 09:46:50 integro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 09:46:51 integro volumio[3267]: info: TidalConnect service started! Jul 09 09:46:51 integro volumio[3267]: info: Completed starting Core Plugins Jul 09 09:46:51 integro volumio[3267]: info: ------------------------------------------- Jul 09 09:46:51 integro volumio[3267]: info: ----- MyVolumio plugins startup ---- Jul 09 09:46:51 integro volumio[3267]: info: ------------------------------------------- Jul 09 09:46:51 integro volumio[3267]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:51 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 09 09:46:51 integro volumio[3267]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Jul 09 09:46:51 integro volumio[3267]: info: Received Get System Info Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 09:46:51 integro volumio[3267]: info: Discovery: Getting this device information Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:51 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:51 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:51 integro volumio[3267]: info: Listing playlists Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 09 09:46:51 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:51 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:51 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:51 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:51 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:52 integro ntpd[3056]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 09 09:46:52 integro mpd[3833]: exception: No database Jul 09 09:46:52 integro volumio[3267]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jul 09 09:46:52 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 09:46:52 integro volumio[3267]: info: Received Get System Info Jul 09 09:46:52 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 09:46:52 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 09:46:52 integro volumio[3267]: info: Discovery: Getting this device information Jul 09 09:46:52 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:46:52 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:46:52 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 09:46:53 integro sudo[4086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=sfingesicilia,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.0.172/share_sda1/Music /mnt/NAS/The_Sound Jul 09 09:46:53 integro sudo[4086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:53 integro volumio[3267]: info: Initializing connection to go-librespot Websocket Jul 09 09:46:53 integro kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jul 09 09:46:53 integro kernel: CIFS VFS: cifs_mount failed w/return code = -101 Jul 09 09:46:53 integro sudo[4086]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:53 integro volumio[3267]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 09 09:46:53 integro volumio[3267]: info: Cannot mount NAS The_Sound at system boot, trial number 3 ,retrying in 5 seconds Jul 09 09:46:53 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 09 09:46:53 integro systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 09 09:46:53 integro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jul 09 09:46:53 integro systemd[1]: Stopped go-librespot Daemon. Jul 09 09:46:53 integro systemd[1]: Started go-librespot Daemon. Jul 09 09:46:53 integro volumio[3267]: info: Executing endpoint metavolumio Jul 09 09:46:53 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 09 09:46:53 integro go-librespot[4090]: go-librespot daemon starting... Jul 09 09:46:53 integro volumio[3267]: info: Executing endpoint metavolumio Jul 09 09:46:53 integro go-librespot[4090]: time="2025-07-09T09:46:53+02:00" level=info msg="running go-librespot 0.2.0" Jul 09 09:46:53 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 09 09:46:53 integro go-librespot[4090]: time="2025-07-09T09:46:53+02:00" level=debug msg="app state loaded" Jul 09 09:46:53 integro go-librespot[4090]: time="2025-07-09T09:46:53+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 09:46:53 integro volumio[3267]: error: Failed request for metavolumio API Jul 09 09:46:53 integro go-librespot[4090]: time="2025-07-09T09:46:53+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" Jul 09 09:46:53 integro volumio[3267]: error: Failed request for metavolumio API Jul 09 09:46:53 integro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 09 09:46:53 integro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 09 09:46:55 integro ntpd[3056]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 09 09:46:55 integro volumio[3267]: info: Checking for updated MCU Firmware Jul 09 09:46:55 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 09:46:55 integro volumio[3267]: info: Firware on device is on latest version, no need to update Jul 09 09:46:56 integro ntpd[3056]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 09 09:46:56 integro volumio[3267]: info: Initializing connection to go-librespot Websocket Jul 09 09:46:56 integro volumio[3267]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 09 09:46:56 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:46:56 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:46:56 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:46:56 integro volumio[3267]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=mp1&variante=integro&version=3.816&uuid=721d0395b66cdfcc42caaf3ead3f45f5" http://updates.volumio.org/downloader-v1/track-device Jul 09 09:46:56 integro volumio[3267]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:46:56 integro volumio[3267]: Dload Upload Total Spent Left Speed Jul 09 09:46:56 integro volumio[3267]: [132B blob data] Jul 09 09:46:56 integro volumio[3267]: retrying in 5 seconds, trial 0 Jul 09 09:46:56 integro volumio[3267]: info: Volumio Calling Home Jul 09 09:46:56 integro systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 09 09:46:56 integro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jul 09 09:46:56 integro systemd[1]: Stopped go-librespot Daemon. Jul 09 09:46:56 integro systemd[1]: Started go-librespot Daemon. Jul 09 09:46:56 integro go-librespot[4105]: go-librespot daemon starting... Jul 09 09:46:56 integro wpa_supplicant[3173]: wlan0: Trying to associate with 68:ff:7b:56:00:43 (SSID='Casita' freq=2417 MHz) Jul 09 09:46:56 integro go-librespot[4105]: time="2025-07-09T09:46:56+02:00" level=info msg="running go-librespot 0.2.0" Jul 09 09:46:56 integro go-librespot[4105]: time="2025-07-09T09:46:56+02:00" level=debug msg="app state loaded" Jul 09 09:46:56 integro kernel: Connecting with 68:ff:7b:56:00:43 ssid "Casita", len (6) channel=2 Jul 09 09:46:56 integro kernel: dhd_dbg_start_pkt_monitor, 1724 Jul 09 09:46:56 integro go-librespot[4105]: time="2025-07-09T09:46:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 09:46:56 integro go-librespot[4105]: time="2025-07-09T09:46:56+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" Jul 09 09:46:56 integro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 09 09:46:56 integro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 09 09:46:58 integro ntpd[3056]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 09 09:46:58 integro sudo[4113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=sfingesicilia,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.0.172/share_sda1/Music /mnt/NAS/The_Sound Jul 09 09:46:58 integro sudo[4113]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:46:58 integro kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jul 09 09:46:58 integro kernel: CIFS VFS: cifs_mount failed w/return code = -101 Jul 09 09:46:58 integro sudo[4113]: pam_unix(sudo:session): session closed for user root Jul 09 09:46:58 integro volumio[3267]: info: Cannot mount NAS The_Sound at system boot, trial number 4 ,retrying in 5 seconds Jul 09 09:46:59 integro volumio[3267]: info: Initializing connection to go-librespot Websocket Jul 09 09:46:59 integro volumio[3267]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 09 09:47:00 integro systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 09 09:47:00 integro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jul 09 09:47:00 integro systemd[1]: Stopped go-librespot Daemon. Jul 09 09:47:00 integro systemd[1]: Started go-librespot Daemon. Jul 09 09:47:00 integro go-librespot[4134]: go-librespot daemon starting... Jul 09 09:47:00 integro go-librespot[4134]: time="2025-07-09T09:47:00+02:00" level=info msg="running go-librespot 0.2.0" Jul 09 09:47:00 integro go-librespot[4134]: time="2025-07-09T09:47:00+02:00" level=debug msg="app state loaded" Jul 09 09:47:00 integro go-librespot[4134]: time="2025-07-09T09:47:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 09:47:00 integro go-librespot[4134]: time="2025-07-09T09:47:00+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" Jul 09 09:47:00 integro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 09 09:47:00 integro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 09 09:47:00 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 09 09:47:01 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:47:01 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:47:01 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:47:02 integro volumio[3267]: info: Initializing connection to go-librespot Websocket Jul 09 09:47:02 integro volumio[3267]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 09 09:47:03 integro kernel: wl_iw_event: Link UP with 68:ff:7b:56:00:43 Jul 09 09:47:03 integro kernel: wl_bss_connect_done succeeded with 68:ff:7b:56:00:43 Jul 09 09:47:03 integro kernel: CFG80211-ERROR) wl_cfg80211_scan_abort : scan abort failed Jul 09 09:47:03 integro wpa_supplicant[3173]: wlan0: Associated with 68:ff:7b:56:00:43 Jul 09 09:47:03 integro wpa_supplicant[3173]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 09 09:47:03 integro wpa_supplicant[3173]: wlan0: WPA: Key negotiation completed with 68:ff:7b:56:00:43 [PTK=CCMP GTK=TKIP] Jul 09 09:47:03 integro kernel: wl_bss_connect_done succeeded with 68:ff:7b:56:00:43 vndr_oui: 8C-FD-F0 Jul 09 09:47:03 integro wpa_supplicant[3173]: wlan0: CTRL-EVENT-CONNECTED - Connection to 68:ff:7b:56:00:43 completed [id=0 id_str=] Jul 09 09:47:03 integro dhcpcd[3385]: wlan0: carrier acquired Jul 09 09:47:03 integro dhcpcd[3385]: wlan0: IAID 6b:ca:dc:47 Jul 09 09:47:03 integro dhcpcd[3385]: wlan0: rebinding lease of 192.168.0.247 Jul 09 09:47:03 integro systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 09 09:47:03 integro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jul 09 09:47:03 integro systemd[1]: Stopped go-librespot Daemon. Jul 09 09:47:03 integro systemd[1]: Started go-librespot Daemon. Jul 09 09:47:03 integro go-librespot[4151]: go-librespot daemon starting... Jul 09 09:47:03 integro sudo[4150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=sfingesicilia,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.0.172/share_sda1/Music /mnt/NAS/The_Sound Jul 09 09:47:03 integro sudo[4150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:47:03 integro kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jul 09 09:47:03 integro kernel: CIFS VFS: cifs_mount failed w/return code = -101 Jul 09 09:47:03 integro sudo[4150]: pam_unix(sudo:session): session closed for user root Jul 09 09:47:03 integro go-librespot[4151]: time="2025-07-09T09:47:03+02:00" level=info msg="running go-librespot 0.2.0" Jul 09 09:47:03 integro go-librespot[4151]: time="2025-07-09T09:47:03+02:00" level=debug msg="app state loaded" Jul 09 09:47:03 integro volumio[3267]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jul 09 09:47:03 integro go-librespot[4151]: time="2025-07-09T09:47:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 09:47:03 integro go-librespot[4151]: time="2025-07-09T09:47:03+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" Jul 09 09:47:03 integro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 09 09:47:03 integro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 09 09:47:03 integro dhcpcd[3385]: wlan0: soliciting an IPv6 router Jul 09 09:47:05 integro volumio[3267]: info: Initializing connection to go-librespot Websocket Jul 09 09:47:05 integro volumio[3267]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 09 09:47:06 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:47:06 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:47:06 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:47:06 integro systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 09 09:47:06 integro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jul 09 09:47:06 integro systemd[1]: Stopped go-librespot Daemon. Jul 09 09:47:06 integro systemd[1]: Started go-librespot Daemon. Jul 09 09:47:06 integro go-librespot[4167]: go-librespot daemon starting... Jul 09 09:47:06 integro go-librespot[4167]: time="2025-07-09T09:47:06+02:00" level=info msg="running go-librespot 0.2.0" Jul 09 09:47:06 integro go-librespot[4167]: time="2025-07-09T09:47:06+02:00" level=debug msg="app state loaded" Jul 09 09:47:06 integro go-librespot[4167]: time="2025-07-09T09:47:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 09:47:06 integro go-librespot[4167]: time="2025-07-09T09:47:06+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" Jul 09 09:47:06 integro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 09 09:47:06 integro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 09 09:47:06 integro volumio[3267]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=mp1&variante=integro&version=3.816&uuid=721d0395b66cdfcc42caaf3ead3f45f5" http://updates.volumio.org/downloader-v1/track-device Jul 09 09:47:06 integro volumio[3267]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:47:06 integro volumio[3267]: Dload Upload Total Spent Left Speed Jul 09 09:47:06 integro volumio[3267]: [132B blob data] Jul 09 09:47:06 integro volumio[3267]: retrying in 5 seconds, trial 1 Jul 09 09:47:06 integro volumio[3267]: info: Volumio Calling Home Jul 09 09:47:08 integro volumio[3267]: info: Initializing connection to go-librespot Websocket Jul 09 09:47:08 integro volumio[3267]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 09 09:47:08 integro dhcpcd[3385]: wlan0: probing for an IPv4LL address Jul 09 09:47:08 integro dhcpcd[3385]: wlan0: DHCP lease expired Jul 09 09:47:08 integro dhcpcd[3385]: wlan0: soliciting a DHCP lease Jul 09 09:47:09 integro systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 09 09:47:09 integro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jul 09 09:47:09 integro systemd[1]: Stopped go-librespot Daemon. Jul 09 09:47:09 integro systemd[1]: Started go-librespot Daemon. Jul 09 09:47:09 integro go-librespot[4184]: go-librespot daemon starting... Jul 09 09:47:09 integro go-librespot[4184]: time="2025-07-09T09:47:09+02:00" level=info msg="running go-librespot 0.2.0" Jul 09 09:47:09 integro go-librespot[4184]: time="2025-07-09T09:47:09+02:00" level=debug msg="app state loaded" Jul 09 09:47:09 integro go-librespot[4184]: time="2025-07-09T09:47:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 09:47:09 integro go-librespot[4184]: time="2025-07-09T09:47:09+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" Jul 09 09:47:09 integro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 09 09:47:09 integro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 09 09:47:11 integro volumio[3267]: error: MyVolumio Plugin failed to start in a timely fashion Jul 09 09:47:11 integro volumio[3267]: [Metrics] CommandRouter: 55s 157.93ms Jul 09 09:47:11 integro volumio[3267]: info: CoreCommandRouter::volumiosetStartupVolume Jul 09 09:47:11 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:47:11 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:47:11 integro volumio[3267]: info: CoreCommandRouter::Close All Modals sent Jul 09 09:47:11 integro volumio[3267]: info: CoreCommandRouter::Close All Modals sent Jul 09 09:47:11 integro volumio[3267]: info: Initializing connection to go-librespot Websocket Jul 09 09:47:11 integro volumio[3267]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 09 09:47:11 integro kernel: aml_tdm_open Jul 09 09:47:11 integro kernel: Not init audio effects Jul 09 09:47:11 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 09 09:47:11 integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 09 09:47:11 integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 09 09:47:11 integro kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 09 09:47:11 integro kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d292a18, id(1), clksel(1) Jul 09 09:47:11 integro kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 09 09:47:11 integro kernel: dump_pcm_setting(ffffffc03d292a18) Jul 09 09:47:11 integro kernel: pcm_mode(1) Jul 09 09:47:11 integro kernel: sysclk(11289600) Jul 09 09:47:11 integro kernel: sysclk_bclk_ratio(4) Jul 09 09:47:11 integro kernel: bclk(2822400) Jul 09 09:47:11 integro kernel: bclk_lrclk_ratio(64) Jul 09 09:47:11 integro kernel: lrclk(44100) Jul 09 09:47:11 integro kernel: tx_mask(0x3) Jul 09 09:47:11 integro kernel: rx_mask(0x3) Jul 09 09:47:11 integro kernel: slots(2) Jul 09 09:47:11 integro kernel: slot_width(32) Jul 09 09:47:11 integro kernel: lane_mask_in(0x2) Jul 09 09:47:11 integro kernel: lane_mask_out(0x1) Jul 09 09:47:11 integro kernel: lane_oe_mask_in(0x0) Jul 09 09:47:11 integro kernel: lane_oe_mask_out(0x0) Jul 09 09:47:11 integro kernel: lane_lb_mask_in(0x0) Jul 09 09:47:11 integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 09 09:47:11 integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 09 09:47:11 integro kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 09 09:47:11 integro kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 09 09:47:11 integro kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 09 09:47:11 integro kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 09 09:47:11 integro kernel: aml_tdm_prepare(), reset fddr Jul 09 09:47:11 integro kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 09 09:47:11 integro kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 09 09:47:11 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 09 09:47:11 integro kernel: tdm playback mute: 0, lane_cnt = 8 Jul 09 09:47:11 integro kernel: aml_tdm_prepare(), reset fddr Jul 09 09:47:11 integro kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 09 09:47:11 integro kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 09 09:47:11 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 09 09:47:11 integro kernel: tdm playback mute: 0, lane_cnt = 8 Jul 09 09:47:11 integro kernel: asoc-aml-card auge_sound: tdm playback enable Jul 09 09:47:11 integro kernel: spdif_a is set to enable Jul 09 09:47:11 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:47:11 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:47:11 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:47:12 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 09 09:47:12 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 09 09:47:12 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 09 09:47:13 integro dhcpcd[3385]: wlan0: using IPv4LL address 169.254.10.224 Jul 09 09:47:13 integro avahi-daemon[2845]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.10.224. Jul 09 09:47:13 integro avahi-daemon[2845]: New relevant interface wlan0.IPv4 for mDNS. Jul 09 09:47:13 integro avahi-daemon[2845]: Registering new address record for 169.254.10.224 on wlan0.IPv4. Jul 09 09:47:13 integro dhcpcd[3385]: wlan0: adding route to 169.254.0.0/16 Jul 09 09:47:13 integro dhcpcd[3385]: wlan0: adding default route Jul 09 09:47:13 integro systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 09 09:47:13 integro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jul 09 09:47:13 integro systemd[1]: Stopped go-librespot Daemon. Jul 09 09:47:13 integro systemd[1]: Started go-librespot Daemon. Jul 09 09:47:13 integro go-librespot[4230]: go-librespot daemon starting... Jul 09 09:47:13 integro go-librespot[4230]: time="2025-07-09T09:47:13+02:00" level=info msg="running go-librespot 0.2.0" Jul 09 09:47:13 integro go-librespot[4230]: time="2025-07-09T09:47:13+02:00" level=debug msg="app state loaded" Jul 09 09:47:13 integro go-librespot[4230]: time="2025-07-09T09:47:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 09:47:14 integro ntpd[3056]: Listen normally on 3 wlan0 169.254.10.224:123 Jul 09 09:47:14 integro ntpd[3056]: new interface(s) found: waking up resolver Jul 09 09:47:14 integro kernel: nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based firewall rule not found. Use the iptables CT target to attach helpers instead. Jul 09 09:47:14 integro nmbd[3055]: [2025/07/09 09:47:14.235404, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 09 09:47:14 integro systemd[1]: Started Samba NMB Daemon. Jul 09 09:47:14 integro nmbd[3055]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 09 09:47:14 integro systemd[1]: Starting Samba Winbind Daemon... Jul 09 09:47:14 integro volumio[3267]: info: Initializing connection to go-librespot Websocket Jul 09 09:47:14 integro go-librespot[4230]: time="2025-07-09T09:47:14+02:00" level=debug msg="new websocket client" Jul 09 09:47:14 integro volumio[3267]: info: Connection to go-librespot Websocket established Jul 09 09:47:14 integro winbindd[4248]: [2025/07/09 09:47:14.579207, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 09 09:47:14 integro winbindd[4248]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 09 09:47:14 integro winbindd[4248]: [2025/07/09 09:47:14.591873, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 09 09:47:14 integro winbindd[4248]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 09 09:47:14 integro systemd[1]: Started Samba Winbind Daemon. Jul 09 09:47:14 integro systemd[1]: Starting Samba SMB Daemon... Jul 09 09:47:15 integro smbd[4254]: [2025/07/09 09:47:15.123829, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 09 09:47:15 integro smbd[4254]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 09 09:47:15 integro systemd[1]: Started Samba SMB Daemon. Jul 09 09:47:15 integro dhcpcd[3385]: wlan0: offered 192.168.0.247 from 192.168.0.1 Jul 09 09:47:15 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 09 09:47:15 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:47:15 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 09 09:47:16 integro kernel: asoc-aml-card auge_sound: tdm playback stop Jul 09 09:47:16 integro kernel: spdif_a is set to disable Jul 09 09:47:16 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 09 09:47:16 integro kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 09 09:47:16 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jul 09 09:47:16 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 09 09:47:16 integro volumio[3788]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 09 09:47:16 integro volumio-time-update[2831]: volumio-time-update-util: Fetching time from Volumio... Jul 09 09:47:16 integro volumio[3267]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=mp1&variante=integro&version=3.816&uuid=721d0395b66cdfcc42caaf3ead3f45f5" http://updates.volumio.org/downloader-v1/track-device Jul 09 09:47:16 integro volumio[3267]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 09 09:47:16 integro volumio[3267]: Dload Upload Total Spent Left Speed Jul 09 09:47:16 integro volumio[3267]: [132B blob data] Jul 09 09:47:16 integro volumio[3267]: retrying in 5 seconds, trial 2 Jul 09 09:47:16 integro volumio[3267]: info: Volumio Calling Home Jul 09 09:47:17 integro volumio[3267]: info: Getting Spotify volume Jul 09 09:47:18 integro volumio[3267]: info: BOOT COMPLETED Jul 09 09:47:18 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:47:18 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:47:18 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:47:18 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:47:18 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:47:18 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 09:47:18 integro volumio[3267]: info: Not Reporting Auto name since its the default one Jul 09 09:47:18 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 09 09:47:18 integro sudo[4281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 09 09:47:18 integro sudo[4281]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:47:18 integro sudo[4281]: pam_unix(sudo:session): session closed for user root Jul 09 09:47:18 integro sudo[4283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 09 09:47:18 integro sudo[4283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:47:18 integro systemd[1]: Started RAAT DAEMON. Jul 09 09:47:18 integro sudo[4283]: pam_unix(sudo:session): session closed for user root Jul 09 09:47:18 integro volumio[3267]: info: Raat Daemon started successfully Jul 09 09:47:18 integro kernel: aml_tdm_open Jul 09 09:47:18 integro kernel: Not init audio effects Jul 09 09:47:18 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 09 09:47:18 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jul 09 09:47:18 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 09 09:47:18 integro kernel: aml_tdm_open Jul 09 09:47:18 integro kernel: Not init audio effects Jul 09 09:47:18 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 09 09:47:18 integro kernel: tdm playback mute: 1, lane_cnt = 8 Jul 09 09:47:18 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 09 09:47:18 integro volumio[3267]: info: Executing endpoint restartRAATSocket Jul 09 09:47:18 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 09 09:47:18 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:47:19 integro volumio[3267]: info: Reporting MCU Network Status: 2 Jul 09 09:47:19 integro volumio[3267]: info: Volumio Network Manager: Network status updated: 2 Jul 09 09:47:21 integro volumio[3267]: info: RAAT: Requesting Headphone Status Jul 09 09:47:21 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 09 09:47:21 integro volumio[3267]: info: MCU Signalled Headphone Mode Disabled Jul 09 09:47:21 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 09 09:47:21 integro volumio[3267]: info: Reporting Headphone State: false Jul 09 09:47:21 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:47:21 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 09:47:21 integro volumio[3267]: info: Updating RAAT Signal Path Jul 09 09:47:23 integro volumio[3788]: ...............................................................................................++++ Jul 09 09:47:24 integro volumio[3788]: ............++++ Jul 09 09:47:24 integro volumio[3788]: e is 65537 (0x010001) Jul 09 09:47:24 integro volumio[3788]: writing RSA key Jul 09 09:47:25 integro dhcpcd[3385]: wlan0: probing address 192.168.0.247/24 Jul 09 09:47:31 integro dhcpcd[3385]: wlan0: leased 192.168.0.247 for 7200 seconds Jul 09 09:47:31 integro avahi-daemon[2845]: Registering new address record for 192.168.0.247 on wlan0.IPv4. Jul 09 09:47:31 integro dhcpcd[3385]: wlan0: adding route to 192.168.0.0/24 Jul 09 09:47:31 integro dhcpcd[3385]: wlan0: changing default route via 192.168.0.1 Jul 09 09:47:31 integro ntpd[3056]: ntpd exiting on signal 15 (Terminated) Jul 09 09:47:31 integro systemd[1]: Stopping Network Time Service... Jul 09 09:47:31 integro systemd[1]: ntp.service: Succeeded. Jul 09 09:47:31 integro systemd[1]: Stopped Network Time Service. Jul 09 09:47:31 integro systemd[1]: Starting Network Time Service... Jul 09 09:47:31 integro ntpd[4378]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 09 09:47:31 integro ntpd[4378]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 09 09:47:31 integro systemd[1]: Started Network Time Service. Jul 09 09:47:31 integro ntpd[4392]: proto: precision = 1.250 usec (-20) Jul 09 09:47:31 integro ntpd[4392]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 09 09:47:31 integro ntpd[4392]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 09 09:47:31 integro avahi-daemon[2845]: Withdrawing address record for 169.254.10.224 on wlan0. Jul 09 09:47:31 integro avahi-daemon[2845]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.10.224. Jul 09 09:47:31 integro ntpd[4392]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 925 days ago Jul 09 09:47:31 integro dhcpcd[3385]: wlan0: deleting route to 169.254.0.0/16 Jul 09 09:47:31 integro avahi-daemon[2845]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.247. Jul 09 09:47:31 integro ntpd[4392]: Listen and drop on 0 v6wildcard [::]:123 Jul 09 09:47:31 integro ntpd[4392]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 09 09:47:31 integro ntpd[4392]: Listen normally on 2 lo 127.0.0.1:123 Jul 09 09:47:31 integro ntpd[4392]: Listen normally on 3 wlan0 192.168.0.247:123 Jul 09 09:47:31 integro ntpd[4392]: Listening on routing socket on fd #20 for interface updates Jul 09 09:47:31 integro ntpd[4392]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 09 09:47:31 integro ntpd[4392]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 09 09:47:33 integro ntpd[4392]: Soliciting pool server 193.204.114.105 Jul 09 09:47:33 integro go-librespot[4230]: time="2025-07-09T09:47:33+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" Jul 09 09:47:33 integro volumio[3267]: (node:3267) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 09 09:47:33 integro volumio[3267]: at connResetException (internal/errors.js:639:14) Jul 09 09:47:33 integro volumio[3267]: at Socket.socketOnEnd (_http_client.js:499:23) Jul 09 09:47:33 integro volumio[3267]: at Socket.emit (events.js:412:35) Jul 09 09:47:33 integro volumio[3267]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 09 09:47:33 integro volumio[3267]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 09 09:47:33 integro volumio[3267]: (node:3267) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Jul 09 09:47:33 integro volumio[3267]: (node:3267) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Jul 09 09:47:33 integro systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 09 09:47:33 integro systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 09 09:47:33 integro volumio[3267]: info: Connection to go-librespot Websocket closed Jul 09 09:47:33 integro ntpd[4392]: Soliciting pool server 212.45.144.3 Jul 09 09:47:33 integro ntpd[4392]: Soliciting pool server 185.19.184.35 Jul 09 09:47:33 integro volumio[3267]: info: Discovery: adding 9cdc4e22-0ba7-4d8e-acb5-f3022841e55e Jul 09 09:47:33 integro volumio[3267]: info: Discovery: Found device Integro Jul 09 09:47:33 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:47:33 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:47:33 integro volumio[3267]: info: MRS: Pushing multiroomSync output for this device Jul 09 09:47:33 integro volumio[3267]: info: MRS: Pushing multiroomSync output Jul 09 09:47:33 integro volumio[3267]: info: Adding audio output: Jul 09 09:47:33 integro volumio[3267]: info: Adding audio output: Jul 09 09:47:34 integro volumio[3267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 09 09:47:34 integro volumio[3267]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 09 09:47:34 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:47:34 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:47:34 integro volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Jul 09 09:47:34 integro volumio[3267]: SPOTIFY: SPOTIFY VOLUME undefined Jul 09 09:47:34 integro volumio[3267]: SPOTIFY: VOLUMIO VOLUME 30 Jul 09 09:47:34 integro volumio[3267]: info: Aligning Spotify Volume to Volumio Volume Jul 09 09:47:34 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:47:34 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:47:34 integro volumio[3267]: info: Setting Spotify Volume from Volumio: 30 Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 09 09:47:34 integro volumio[3267]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 09 09:47:34 integro ntpd[4392]: Soliciting pool server 162.159.200.1 Jul 09 09:47:34 integro ntpd[4392]: Soliciting pool server 162.159.200.123 Jul 09 09:47:34 integro ntpd[4392]: Soliciting pool server 212.45.144.206 Jul 09 09:47:35 integro ntpd[4392]: Soliciting pool server 151.22.209.90 Jul 09 09:47:35 integro ntpd[4392]: Soliciting pool server 185.157.229.254 Jul 09 09:47:35 integro ntpd[4392]: Soliciting pool server 85.199.214.99 Jul 09 09:47:35 integro ntpd[4392]: Soliciting pool server 195.32.70.195 Jul 09 09:47:36 integro volumio[3267]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 09 09:47:36 integro volumio[3267]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 09 09:47:36 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:47:36 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 09:47:36 integro volumio[3267]: info: Starting MyVolumio Remote Streaming Endpoints Jul 09 09:47:36 integro volumio[3267]: info: MyVolumio login type: Token Jul 09 09:47:36 integro volumio[3267]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 09 09:47:36 integro volumio[3267]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Jul 09 09:47:36 integro systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 09 09:47:36 integro systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jul 09 09:47:36 integro systemd[1]: Stopped go-librespot Daemon. Jul 09 09:47:36 integro systemd[1]: Started go-librespot Daemon. Jul 09 09:47:36 integro volumio[3267]: SPOTIFY: SETTING SPOTIFY VOLUME 30 Jul 09 09:47:36 integro volumio[3267]: info: Sending Spotify command with payload to local API: /player/volume Jul 09 09:47:36 integro go-librespot[4423]: go-librespot daemon starting... Jul 09 09:47:36 integro volumio[3267]: info: Initializing connection to go-librespot Websocket Jul 09 09:47:36 integro volumio[3267]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 09 09:47:36 integro go-librespot[4423]: time="2025-07-09T09:47:36+02:00" level=info msg="running go-librespot 0.2.0" Jul 09 09:47:36 integro go-librespot[4423]: time="2025-07-09T09:47:36+02:00" level=debug msg="app state loaded" Jul 09 09:47:36 integro go-librespot[4423]: time="2025-07-09T09:47:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 09 09:47:36 integro volumio[3267]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 09 09:47:36 integro volumio-time-update[2831]: volumio-time-update-util: Date not found in response Jul 09 09:47:36 integro volumio-time-update[2831]: volumio-time-update-util: Retrying in 5 seconds... Jul 09 09:47:36 integro ntpd[4392]: Soliciting pool server 89.46.74.148 Jul 09 09:47:36 integro ntpd[4392]: Soliciting pool server 93.94.88.50 Jul 09 09:47:36 integro ntpd[4392]: Soliciting pool server 31.14.133.122 Jul 09 09:47:36 integro volumio[3267]: error: MyVolumio Custom Token format not valid, refreshing it Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=info msg="zeroconf server listening on port 38883" Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=debug msg="obtained new client token: AABbMP2hA9lv8NWV8THA4XHQlIBDyfpjee0Sf1JGuapehAJBsdbZasCNP6n42vUvPgFmqiB/Ip3M06AC5wJatT1UBA3OEMWCWcarAB3DlS1669V0xTOmuDAzOUGOOxa1oq+xxPiTgZ0t6s3ZEh7kMwdGfVtWRThVfxZfgwCTaeiH9PVVdzngwV3zaj82UdbkdHe97mPzfkPq2MVXf7lt8yTBJ1c3Pps1I1s9O9nUaK3M3JGP8RSLZL0=" Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jul 09 09:47:37 integro ntpd[4392]: Soliciting pool server 81.56.230.156 Jul 09 09:47:37 integro ntpd[4392]: Soliciting pool server 37.247.53.178 Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=debug msg="completed keyexchange" Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=debug msg="completed challenge" Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=info msg="authenticated AP as pizzipao" Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=info msg="authenticated Login5 as pizzipao" Jul 09 09:47:37 integro go-librespot[4423]: time="2025-07-09T09:47:37+02:00" level=debug msg="initializing zeroconf session, username: pizzipao" Jul 09 09:47:37 integro volumio[3267]: info: MyVolumio login type: Token Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=debug msg="dealer connection opened" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=trace msg="starting accesspoint recv loop" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=trace msg="starting dealer recv loop" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=trace msg="received accesspoint ping" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=debug msg="received connection id: NWI1OTM2YjctNmQzOC00MjBhLWFmODMtMDQ1MzdkZmEwNTJlK2RlYWxlcit0Y3A6Ly8wYWIxNTA0YS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNDQ4MzQ5RTNDOTlDRjI4Q0UxNjY3NTEyNUJBN0U5MjE1MTdFQUYzQUFBN0M2MjM4RkFBOEMwMDdCNTZEM0FFOQ==" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=trace msg="received accesspoint pong ack" Jul 09 09:47:38 integro go-librespot[4423]: time="2025-07-09T09:47:38+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 09 09:47:38 integro volumio[3267]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 09 09:47:38 integro ntpd[4392]: Soliciting pool server 93.94.88.51 Jul 09 09:47:38 integro ntpd[4392]: Soliciting pool server 2a00:6d41:10:1194::3 Jul 09 09:47:39 integro volumio[3267]: info: MyVolumio token set successfully Jul 09 09:47:39 integro volumio[3267]: info: MYVOLUMIO: Adding device Jul 09 09:47:39 integro volumio[3267]: info: MYVOLUMIO: Evaluating Server Jul 09 09:47:38 integro ntpd[4392]: receive: Unexpected origin timestamp 0xec189d1b.9961524c does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xec189d1a.df934554 Jul 09 09:47:38 integro ntpd[4392]: receive: Unexpected origin timestamp 0xec189d1b.995bf6cd does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xec189d1a.df70236e Jul 09 09:47:38 integro ntpd[4392]: receive: Unexpected origin timestamp 0xec189d1b.9966c512 does not match aorg 0000000000.00000000 from server@212.45.144.3 xmt 0xec189d1a.e06f281e Jul 09 09:47:38 integro ntpd[4392]: receive: Unexpected origin timestamp 0xec189d1b.99561cdc does not match aorg 0000000000.00000000 from server@212.45.144.206 xmt 0xec189d1a.e00cf86f Jul 09 09:47:38 integro ntpd[4392]: receive: Unexpected origin timestamp 0xec189d1b.99439298 does not match aorg 0000000000.00000000 from server@151.22.209.90 xmt 0xec189d1a.e06243d7 Jul 09 09:47:38 integro ntpd[4392]: receive: Unexpected origin timestamp 0xec189d1b.993ddf09 does not match aorg 0000000000.00000000 from server@85.199.214.99 xmt 0xec189d1a.e25ae5b5 Jul 09 09:47:38 integro volumio[3267]: info: Initializing connection to go-librespot Websocket Jul 09 09:47:38 integro volumio[3267]: info: MyVolumio status changed Jul 09 09:47:38 integro volumio[3267]: info: Streaming services startup Jul 09 09:47:38 integro volumio[3267]: info: Starting Streaming Daemon Jul 09 09:47:39 integro volumio[3267]: info: Removing browser output: myVolumio user plan is not superstar Jul 09 09:47:39 integro volumio[3267]: info: Removing audio output: Jul 09 09:47:39 integro volumio[3267]: info: Stoppping Tunnel 1 Jul 09 09:47:39 integro sudo[4467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 09 09:47:39 integro sudo[4467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:47:39 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 09 09:47:39 integro volumio[3267]: info: Received Get System Info Jul 09 09:47:39 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 09:47:39 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 09:47:39 integro sudo[4467]: pam_unix(sudo:session): session closed for user root Jul 09 09:47:39 integro volumio[3267]: info: Discovery: Getting this device information Jul 09 09:47:39 integro volumio[3267]: info: CoreCommandRouter::volumioGetState Jul 09 09:47:39 integro volumio[3267]: info: CorePlayQueue::getTrack 0 Jul 09 09:47:39 integro volumio[3267]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 09:47:39 integro sudo[4470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 09 09:47:39 integro sudo[4470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 09:47:39 integro volumio[3267]: error: Failed to ping endpoint eu10.myvolumio.org : unknown error Jul 09 09:47:39 integro volumio[3267]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 09:47:39 integro volumio[3267]: Error: Unable to resolve or reject the same promise twice Jul 09 09:47:39 integro volumio[3267]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 09 09:47:39 integro volumio[3267]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Jul 09 09:47:39 integro volumio[3267]: at Socket.emit (events.js:400:28) Jul 09 09:47:39 integro volumio[3267]: at addChunk (internal/streams/readable.js:293:12) Jul 09 09:47:39 integro volumio[3267]: at readableAddChunk (internal/streams/readable.js:267:9) Jul 09 09:47:39 integro volumio[3267]: at Socket.Readable.push (internal/streams/readable.js:206:10) Jul 09 09:47:39 integro volumio[3267]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jul 09 09:47:39 integro volumio[3267]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 09:47:39 integro sudo[4470]: pam_unix(sudo:session): session closed for user root Jul 09 09:47:39 integro sudo[4498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-09 09:46 Jul 09 09:47:39 integro sudo[4498]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="integro" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 07:15:43 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Integro" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Integro" VOLUMIO_HASH="f2ea6fc36c3df7c3e2db25c5fbfad88c"