Oct 31 11:46:13 volumio-beta-storevegen-59 ntpd[913]: CLOCK: time stepped by 1268779.202261 Oct 31 11:46:13 volumio-beta-storevegen-59 ntpd[913]: CLOCK: time changed from 2025-10-16 to 2025-10-31 Oct 31 11:46:13 volumio-beta-storevegen-59 ntpd[913]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 31 11:46:13 volumio-beta-storevegen-59 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "network"... Oct 31 11:46:13 volumio-beta-storevegen-59 systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Oct 31 11:46:13 volumio-beta-storevegen-59 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 31 11:46:13 volumio-beta-storevegen-59 systemd[1]: e2scrub_all.service: Deactivated successfully. Oct 31 11:46:13 volumio-beta-storevegen-59 systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Oct 31 11:46:13 volumio-beta-storevegen-59 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Refreshing Cached IP Addresses Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "services"... Oct 31 11:46:13 volumio-beta-storevegen-59 systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "alsa_controller"... Oct 31 11:46:13 volumio-beta-storevegen-59 sudo[1281]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 31 11:46:13 volumio-beta-storevegen-59 sudo[1281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:13 volumio-beta-storevegen-59 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 31 11:46:13 volumio-beta-storevegen-59 sudo[1281]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:13 volumio-beta-storevegen-59 sudo[1286]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 31 11:46:13 volumio-beta-storevegen-59 sudo[1286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:13 volumio-beta-storevegen-59 sudo[1286]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:13 volumio-beta-storevegen-59 sudo[1289]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 31 11:46:13 volumio-beta-storevegen-59 sudo[1289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "wizard"... Oct 31 11:46:13 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:13 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:13 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 31 11:46:13 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "networkfs"... Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Starting Udev Watcher for removable devices Oct 31 11:46:13 volumio-beta-storevegen-59 sudo[1320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Jimmern,password=Creative1024,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.72/Music /mnt/NAS/NAS Oct 31 11:46:13 volumio-beta-storevegen-59 sudo[1320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Ignoring mount for partition: boot Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Ignoring mount for partition: volumio Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Ignoring mount for partition: volumio_data Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "volumio_command_line_client"... Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "upnp"... Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: [1761907573871] Starting Upmpd Daemon Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "my_music"... Oct 31 11:46:13 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "mpd"... Oct 31 11:46:13 volumio-beta-storevegen-59 kernel: netfs: FS-Cache loaded Oct 31 11:46:14 volumio-beta-storevegen-59 kernel: Key type cifs.spnego registered Oct 31 11:46:14 volumio-beta-storevegen-59 kernel: Key type cifs.idmap registered Oct 31 11:46:14 volumio-beta-storevegen-59 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 31 11:46:14 volumio-beta-storevegen-59 kernel: CIFS: Attempting to mount //192.168.68.72/Music Oct 31 11:46:14 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "upnp_browser"... Oct 31 11:46:14 volumio-beta-storevegen-59 sudo[1320]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:15 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "alarm-clock"... Oct 31 11:46:15 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "airplay_emulation"... Oct 31 11:46:15 volumio-beta-storevegen-59 volumio[1226]: info: Starting Shairport Sync Oct 31 11:46:15 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "last_100"... Oct 31 11:46:15 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "webradio"... Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "i2s_dacs"... Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "volumiodiscovery"... Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 31 11:46:16 volumio-beta-storevegen-59 node[1226]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 31 11:46:16 volumio-beta-storevegen-59 node[1226]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: *** WARNING *** For more information see Oct 31 11:46:16 volumio-beta-storevegen-59 node[1226]: *** WARNING *** For more information see Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 31 11:46:16 volumio-beta-storevegen-59 node[1226]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 31 11:46:16 volumio-beta-storevegen-59 node[1226]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: *** WARNING *** For more information see Oct 31 11:46:16 volumio-beta-storevegen-59 node[1226]: *** WARNING *** For more information see Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: info: Discovery: Started advertising with name: Volumio Beta Storevegen 59 Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 31 11:46:16 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "spop"... Oct 31 11:46:16 volumio-beta-storevegen-59 systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 31 11:46:17 volumio-beta-storevegen-59 dhcpcd[578]: timed out Oct 31 11:46:17 volumio-beta-storevegen-59 sh[570]: timed out Oct 31 11:46:17 volumio-beta-storevegen-59 sh[509]: ifup: failed to bring up eth0 Oct 31 11:46:17 volumio-beta-storevegen-59 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 31 11:46:17 volumio-beta-storevegen-59 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 31 11:46:17 volumio-beta-storevegen-59 sudo[1289]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:17 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "outputs"... Oct 31 11:46:17 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "albumart"... Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: Plugin example_plugin is not enabled Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "inputs"... Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "updater_comm"... Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: Plugin mpdemulation is not enabled Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "rest_api"... Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "websocket"... Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: Starting Socket.io Server version 1.7.4 Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: Loading plugin "RoonBridge"... Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: Applying required configuration parameters for plugin RoonBridge Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: Loading i18n strings for locale en Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: Updating browse sources language Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::initPlayerControls Oct 31 11:46:18 volumio-beta-storevegen-59 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: Express server listening on port 3000 Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1226]: [Metrics] WebUI: 8s 796.24ms Oct 31 11:46:18 volumio-beta-storevegen-59 volumio[1362]: Forking 3 albumart workers Oct 31 11:46:19 volumio-beta-storevegen-59 volumio[1226]: info: CoreStateMachine::resetVolumioState Oct 31 11:46:19 volumio-beta-storevegen-59 volumio[1226]: info: CoreStateMachine::getcurrentVolume Oct 31 11:46:19 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioRetrievevolume Oct 31 11:46:19 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:19 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:19 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 31 11:46:19 volumio-beta-storevegen-59 volumio[1226]: info: Volumio Network Manager: Network status updated: 2 Oct 31 11:46:19 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:19 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:19 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: verbose: New Socket.io Connection to 192.168.68.10:3000 from 192.168.68.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: Reloading queue from file Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: verbose: New Socket.io Connection to 192.168.68.10:3000 from 192.168.68.18 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: VolumeController:: Volume=100 Mute =true Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreStateMachine::pushState Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CorePlayQueue::getTrack 0 Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioPushState Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreStateMachine::updateTrackBlock Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CorePlayQueue::getTrackBlock Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioRetrievevolume Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreStateMachine::setRepeat null single undefined Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreStateMachine::pushState Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CorePlayQueue::getTrack 0 Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioPushState Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreStateMachine::setRandom null Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreStateMachine::pushState Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CorePlayQueue::getTrack 0 Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioPushState Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: Setting Device type: Raspberry PI Oct 31 11:46:20 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:20 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:20 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: Completed loading Core Plugins Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: Preparing to generate the ALSA configuration file Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Oct 31 11:46:20 volumio-beta-storevegen-59 volumio[1226]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 31 11:46:21 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:21 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:21 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioGetState Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CorePlayQueue::getTrack 0 Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: Discovery: adding 9542871a-6065-4ed5-8738-2cb8d7b6c5c3 Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: Discovery: Found device Volumio Beta Storevegen 59 Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioGetState Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CorePlayQueue::getTrack 0 Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: Discovery: this is already registered, 9542871a-6065-4ed5-8738-2cb8d7b6c5c3 Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: Discovery: Found device Volumio Beta Storevegen 59 Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioGetState Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CorePlayQueue::getTrack 0 Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: VolumeController:: Volume=100 Mute =true Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CoreStateMachine::pushState Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CorePlayQueue::getTrack 0 Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioPushState Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: Asound.conf file unchanged, so no further update is needed Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: Output device has changed, restarting MPD Oct 31 11:46:21 volumio-beta-storevegen-59 sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 31 11:46:21 volumio-beta-storevegen-59 sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: Output device has changed, restarting Shairport Sync Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 11:46:21 volumio-beta-storevegen-59 sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 31 11:46:21 volumio-beta-storevegen-59 sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:21 volumio-beta-storevegen-59 sudo[1422]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:21 volumio-beta-storevegen-59 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 31 11:46:21 volumio-beta-storevegen-59 systemd[1]: Starting mpd.service - Music Player Daemon... Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: ___________ START PLUGINS ___________ Oct 31 11:46:21 volumio-beta-storevegen-59 sudo[1432]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 31 11:46:21 volumio-beta-storevegen-59 sudo[1432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 31 11:46:21 volumio-beta-storevegen-59 sudo[1433]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 31 11:46:21 volumio-beta-storevegen-59 sudo[1432]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: ControllerMpd::onStart: Initializing MPD Oct 31 11:46:21 volumio-beta-storevegen-59 volumio[1226]: info: Creating MPD Configuration file Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: [1761907582012] CoreMusicLibrary::Adding element Media Servers Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 11:46:22 volumio-beta-storevegen-59 sudo[1438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 31 11:46:22 volumio-beta-storevegen-59 sudo[1438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:22 volumio-beta-storevegen-59 sudo[1436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 31 11:46:22 volumio-beta-storevegen-59 sudo[1436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:22 volumio-beta-storevegen-59 sudo[1436]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:22 volumio-beta-storevegen-59 nmbd[983]: [2025/10/31 11:46:22.202637, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 31 11:46:22 volumio-beta-storevegen-59 nmbd[983]: register_name_response: Answer name <00> differs from question name <00>. Oct 31 11:46:22 volumio-beta-storevegen-59 nmbd[983]: [2025/10/31 11:46:22.202916, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 31 11:46:22 volumio-beta-storevegen-59 nmbd[983]: register_name_response: Answer name <03> differs from question name <03>. Oct 31 11:46:22 volumio-beta-storevegen-59 nmbd[983]: [2025/10/31 11:46:22.203116, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Oct 31 11:46:22 volumio-beta-storevegen-59 nmbd[983]: register_name_response: Answer name <20> differs from question name <20>. Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 11:46:22 volumio-beta-storevegen-59 systemd[1]: mpd.service: Deactivated successfully. Oct 31 11:46:22 volumio-beta-storevegen-59 systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 31 11:46:22 volumio-beta-storevegen-59 systemd[1]: mpd.socket: Deactivated successfully. Oct 31 11:46:22 volumio-beta-storevegen-59 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 31 11:46:22 volumio-beta-storevegen-59 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: [1761907582457] CoreMusicLibrary::Adding element Last_100 Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: [1761907582459] CoreMusicLibrary::Adding element Webradio Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 31 11:46:22 volumio-beta-storevegen-59 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 31 11:46:22 volumio-beta-storevegen-59 systemd[1]: Starting mpd.service - Music Player Daemon... Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: Initializing BBC Radios Oct 31 11:46:22 volumio-beta-storevegen-59 sudo[1451]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 31 11:46:22 volumio-beta-storevegen-59 sudo[1451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 31 11:46:22 volumio-beta-storevegen-59 sudo[1454]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 31 11:46:22 volumio-beta-storevegen-59 sudo[1451]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 31 11:46:22 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 11:46:23 volumio-beta-storevegen-59 volumio[1226]: info: Creating Spotify config file Oct 31 11:46:23 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:25 volumio-beta-storevegen-59 volumio[1226]: info: Volumio Calling Home Oct 31 11:46:25 volumio-beta-storevegen-59 sudo[1482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 31 11:46:25 volumio-beta-storevegen-59 sudo[1482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:25 volumio-beta-storevegen-59 systemd[1]: /lib/systemd/system/roonbridge.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 31 11:46:25 volumio-beta-storevegen-59 systemd[1]: /lib/systemd/system/roonbridge.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 31 11:46:25 volumio-beta-storevegen-59 systemd[1]: Started roonbridge.service - RoonBridge. Oct 31 11:46:25 volumio-beta-storevegen-59 sudo[1482]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:25 volumio-beta-storevegen-59 volumio[1382]: Starting albumart workers Oct 31 11:46:25 volumio-beta-storevegen-59 volumio[1377]: Starting albumart workers Oct 31 11:46:25 volumio-beta-storevegen-59 volumio[1379]: Starting albumart workers Oct 31 11:46:26 volumio-beta-storevegen-59 volumio-remote-updater[705]: [2025-10-31 11:46:26] [connect] Successful connection Oct 31 11:46:26 volumio-beta-storevegen-59 mpd[1455]: 2025-10-31T11:46:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 31 11:46:26 volumio-beta-storevegen-59 RoonBridge[1489]: 00:00:00.010 Warn: get lock file path: /tmp/.rnbgem0- Oct 31 11:46:26 volumio-beta-storevegen-59 RoonBridge[1489]: 00:00:00.375 Trace: [childprocess] using unix child process Oct 31 11:46:26 volumio-beta-storevegen-59 RoonBridge[1489]: 00:00:00.462 Debug: PathForResource, filename: ../.update Oct 31 11:46:26 volumio-beta-storevegen-59 systemd[1]: Started mpd.service - Music Player Daemon. Oct 31 11:46:26 volumio-beta-storevegen-59 sudo[1438]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:26 volumio-beta-storevegen-59 sudo[1424]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:26 volumio-beta-storevegen-59 RoonBridge[1489]: 00:00:00.541 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 31 11:46:27 volumio-beta-storevegen-59 RoonBridge[1489]: 00:00:00.558 Debug: PathForResource, filename: RAATServer Oct 31 11:46:27 volumio-beta-storevegen-59 RoonBridge[1489]: 00:00:00.558 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 31 11:46:27 volumio-beta-storevegen-59 RoonBridge[1489]: Initializing Oct 31 11:46:27 volumio-beta-storevegen-59 RoonBridge[1489]: 00:00:00.566 Debug: PathForResource, filename: RoonBridgeHelper Oct 31 11:46:27 volumio-beta-storevegen-59 RoonBridge[1489]: 00:00:00.566 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 31 11:46:27 volumio-beta-storevegen-59 RoonBridge[1489]: 00:00:00.608 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 31 11:46:27 volumio-beta-storevegen-59 RoonBridge[1489]: Not Running (.o) Oct 31 11:46:27 volumio-beta-storevegen-59 RoonBridge[1489]: 00:00:00.712 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 31 11:46:27 volumio-beta-storevegen-59 RoonBridge[1489]: Running Oct 31 11:46:27 volumio-beta-storevegen-59 volumio[1226]: info: Discovery: adding d734fdf4-907e-4630-92e4-729249c0e2f3 Oct 31 11:46:27 volumio-beta-storevegen-59 volumio[1226]: info: Discovery: Found device STOREVEGEN 59DAK Oct 31 11:46:27 volumio-beta-storevegen-59 sudo[1527]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 31 11:46:27 volumio-beta-storevegen-59 sudo[1527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:27 volumio-beta-storevegen-59 volumio[1226]: info: Discovery: Connecting to remote: 192.168.68.30 Oct 31 11:46:28 volumio-beta-storevegen-59 sudo[1527]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:28 volumio-beta-storevegen-59 sudo[1529]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 31 11:46:28 volumio-beta-storevegen-59 sudo[1529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:28 volumio-beta-storevegen-59 sudo[1529]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:28 volumio-beta-storevegen-59 RoonBridge[1505]: 00:00:00.026 Warn: get lock file path: /tmp/.rnbhgem0- Oct 31 11:46:28 volumio-beta-storevegen-59 volumio[1226]: info: Completed starting Core Plugins Oct 31 11:46:28 volumio-beta-storevegen-59 volumio[1226]: info: ------------------------------------------- Oct 31 11:46:28 volumio-beta-storevegen-59 volumio[1226]: info: ----- MyVolumio plugins startup ---- Oct 31 11:46:28 volumio-beta-storevegen-59 volumio[1226]: info: ------------------------------------------- Oct 31 11:46:28 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 31 11:46:28 volumio-beta-storevegen-59 volumio[1226]: info: MPD Permissions set Oct 31 11:46:28 volumio-beta-storevegen-59 volumio[1226]: info: MPD Permissions set Oct 31 11:46:28 volumio-beta-storevegen-59 volumio[1226]: info: Volumio called home Oct 31 11:46:28 volumio-beta-storevegen-59 volumio[1226]: info: Spotify config file written Oct 31 11:46:29 volumio-beta-storevegen-59 sudo[1549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 31 11:46:29 volumio-beta-storevegen-59 sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioGetState Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CorePlayQueue::getTrack 0 Oct 31 11:46:29 volumio-beta-storevegen-59 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 31 11:46:29 volumio-beta-storevegen-59 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 31 11:46:29 volumio-beta-storevegen-59 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 31 11:46:29 volumio-beta-storevegen-59 go-librespot[1551]: go-librespot daemon starting... Oct 31 11:46:29 volumio-beta-storevegen-59 sudo[1549]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: error: MPD error: The expression evaluated to a falsy value: Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: assert.ok(self.idling) Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: error: The expression evaluated to a falsy value: Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: assert.ok(self.idling) Oct 31 11:46:29 volumio-beta-storevegen-59 volumio-remote-updater[705]: [2025-10-31 11:46:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1761907586 101 Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: Discovery: Connected to remote: 192.168.68.30 Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: MPD running with PID1455 Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: ,establishing connection Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:29+01:00" level=info msg="running go-librespot 0.4.0" Oct 31 11:46:29 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:29+01:00" level=debug msg="app state loaded" Oct 31 11:46:29 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 31 11:46:29 volumio-beta-storevegen-59 volumio[1226]: info: No need to fix Spotify hosts Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01: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-gae2.spotify.com:80]" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=info msg="zeroconf server listening on port 42121" Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: error: updateQueue error: null Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="obtained new client token: AACk7bl2SPl1xCMN8Pi2vAhLhK6qKowOIjPEDcmTGx0bwkXMkFDcWnN9XYeoSrOc9Ut4+qb7crf5NHizUZ1E5IRcE58Y1ydPTXRc5ZU/0xlzQcF8M2n7REBW2wigY9rEXsQqSmGjvCmeza3udXUe6eL0IbziG44fMpLrGmtJtn+slF27nm6B0Nfm9Ms5IpECjmxjtBbMVBEnomMydCi2H097VZzwj4EzmJZiegFymeH93/AaYx7nDTYxqg==" Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: SPOTIFY: BQC9Maa4zaPGZv3nB2xbkFTOWFwfYHEMVjgg2xh6ZBGqT1Wrfmzx7JrSPi-QAE-sbZA_8sssr1eQGvH3tkiXmko6qugnAGRdJIIGrHIMEk34BlkI2wVkMfI9sI49xm0HihBQ5egT2qD4iW6a4VHwoiYAAXF5rCEBdcAIivL_jS38WRVC1QBLncVTJLTYWGxdNwz3wQkgRwUZPqOh9Cp-3ky6h7gVdBDQfqXd8sQrxshax4pFIxNJz0aoIeKsdwT5Nh3X7JnoO_hgBfyr79ceceA2sQLGLdZNZRo5s-DoTYy1x-IrEmBx5rKiFnjbPEPfFfQuvZ5VdwnjaqpZ5F2znOeRd3fVBq3PRln61Xo3fw Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: info: New Spotify access token = BQC9Maa4zaPGZv3nB2xbkFTOWFwfYHEMVjgg2xh6ZBGqT1Wrfmzx7JrSPi-QAE-sbZA_8sssr1eQGvH3tkiXmko6qugnAGRdJIIGrHIMEk34BlkI2wVkMfI9sI49xm0HihBQ5egT2qD4iW6a4VHwoiYAAXF5rCEBdcAIivL_jS38WRVC1QBLncVTJLTYWGxdNwz3wQkgRwUZPqOh9Cp-3ky6h7gVdBDQfqXd8sQrxshax4pFIxNJz0aoIeKsdwT5Nh3X7JnoO_hgBfyr79ceceA2sQLGLdZNZRo5s-DoTYy1x-IrEmBx5rKiFnjbPEPfFfQuvZ5VdwnjaqpZ5F2znOeRd3fVBq3PRln61Xo3fw Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="completed keyexchange" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="completed challenge" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=info msg="authenticated AP" username="ji****n1" Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: error: updateQueue error: null Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=info msg="authenticated Login5" username="ji****n1" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="initializing zeroconf session" username="ji****n1" Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: info: Starting Shairport Sync Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: info: Starting Shairport Sync Oct 31 11:46:30 volumio-beta-storevegen-59 sudo[1582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 11:46:30 volumio-beta-storevegen-59 sudo[1582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:30 volumio-beta-storevegen-59 volumio[1226]: info: Starting Shairport Sync Oct 31 11:46:30 volumio-beta-storevegen-59 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 31 11:46:30 volumio-beta-storevegen-59 systemd[1]: shairport-sync.service: Deactivated successfully. Oct 31 11:46:30 volumio-beta-storevegen-59 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 31 11:46:30 volumio-beta-storevegen-59 systemd[1]: shairport-sync.service: Consumed 1.183s CPU time. Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="dealer connection opened" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=trace msg="starting accesspoint recv loop" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=trace msg="starting dealer recv loop" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=trace msg="received accesspoint ping" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=debug msg="received connection id: Yjk1YzkxZTEtYjI2...OEY0OEI5QTI4RA==" Oct 31 11:46:30 volumio-beta-storevegen-59 sudo[1586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 11:46:30 volumio-beta-storevegen-59 sudo[1586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:30 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:30+01:00" level=trace msg="received accesspoint pong ack" Oct 31 11:46:30 volumio-beta-storevegen-59 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 31 11:46:30 volumio-beta-storevegen-59 sudo[1582]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 11:46:31 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:31+01:00" level=debug msg="put connect state because NEW_DEVICE" Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:31 volumio-beta-storevegen-59 volumio[1226]: info: Shairport-Sync Started Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: shairport-sync.service: Deactivated successfully. Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 31 11:46:31 volumio-beta-storevegen-59 volumio[1226]: Error adding Membership: Error: addMembership EINVAL Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1586]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: shairport-sync.service: Deactivated successfully. Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1588]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1593]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:31 volumio-beta-storevegen-59 volumio[1226]: info: Shairport-Sync Started Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 31 11:46:31 volumio-beta-storevegen-59 mpd_monitor.sh[1672]: MPD Monitor Service: Starting MPD Monitor Service Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1670]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:31 volumio-beta-storevegen-59 sudo[1668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 31 11:46:31 volumio-beta-storevegen-59 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 31 11:46:32 volumio-beta-storevegen-59 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 31 11:46:32 volumio-beta-storevegen-59 mpd_monitor.sh[1680]: MPD Monitor Service: Starting MPD Monitor Service Oct 31 11:46:32 volumio-beta-storevegen-59 sudo[1668]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: info: Upmpdcli Daemon Started Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: info: Shairport-Sync Started Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: info: Successfully started MPD Monitor Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: info: Successfully started MPD Monitor Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioGetState Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: info: CorePlayQueue::getTrack 0 Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: SPOTIFY: User informations: {"country":"NO","display_name":"Sigve Saur Midtun","email":"jmidtun@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/jimmern1"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/jimmern1","id":"jimmern1","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee850d3183d82abd6ccf6a0ca604","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b820d3183d82abd6ccf6a0ca604","width":64}],"product":"premium","type":"user","uri":"spotify:user:jimmern1"} Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: info: Spotify Successfully logged in Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: info: [1761907592637] CoreMusicLibrary::Adding element Spotify Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 31 11:46:32 volumio-beta-storevegen-59 volumio[1226]: Cannot find translation for source Spotify Oct 31 11:46:33 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:33 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 31 11:46:33 volumio-beta-storevegen-59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 31 11:46:33 volumio-beta-storevegen-59 systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 31 11:46:33 volumio-beta-storevegen-59 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 31 11:46:33 volumio-beta-storevegen-59 volumio[1226]: info: go-librespot daemon successfully initialized Oct 31 11:46:35 volumio-beta-storevegen-59 volumio[1226]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 31 11:46:36 volumio-beta-storevegen-59 volumio[1226]: info: Initializing connection to go-librespot Websocket Oct 31 11:46:37 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:46:37+01:00" level=debug msg="new websocket client" Oct 31 11:46:37 volumio-beta-storevegen-59 volumio[1226]: info: Connection to go-librespot Websocket established Oct 31 11:46:41 volumio-beta-storevegen-59 upmpdcli[1734]: writing RSA key Oct 31 11:46:42 volumio-beta-storevegen-59 volumio[1226]: info: Getting Spotify volume Oct 31 11:46:44 volumio-beta-storevegen-59 volumio[1226]: info: Spotify volume: 100 Oct 31 11:46:44 volumio-beta-storevegen-59 volumio[1226]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: Adding plugin bluetooth to MyMusic Plugins Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: Adding plugin multiroom to MyMusic Plugins Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: Adding plugin metavolumio to MyMusic Plugins Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: Adding plugin cd_controller to MyMusic Plugins Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 31 11:46:50 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 31 11:46:54 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 31 11:46:54 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 31 11:46:54 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 11:46:54 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 11:46:54 volumio-beta-storevegen-59 volumio[1226]: info: Starting MyVolumio Remote Streaming Endpoints Oct 31 11:46:54 volumio-beta-storevegen-59 volumio[1226]: info: MyVolumio login type: Token Oct 31 11:46:54 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 31 11:46:54 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: info: Streaming services startup Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: info: Starting Streaming Daemon Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 31 11:46:57 volumio-beta-storevegen-59 sudo[1791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 31 11:46:57 volumio-beta-storevegen-59 sudo[1791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 31 11:46:57 volumio-beta-storevegen-59 sudo[1791]: pam_unix(sudo:session): session closed for user root Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: at new NodeError (node:internal/errors:405:5) Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: at assert (node:internal/assert:14:11) Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: at internalConnectMultiple (node:net:1118:3) Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: at listOnTimeout (node:internal/timers:575:11) Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: at process.processTimers (node:internal/timers:514:7) { Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: code: 'ERR_INTERNAL_ASSERTION' Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: } Oct 31 11:46:57 volumio-beta-storevegen-59 volumio[1226]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 11:47:00 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:47:00+01:00" level=trace msg="sent dealer ping" Oct 31 11:47:00 volumio-beta-storevegen-59 go-librespot[1552]: time="2025-10-31T11:47:00+01:00" level=trace msg="received dealer pong" Oct 31 11:47:01 volumio-beta-storevegen-59 sudo[1816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-31 11:46' Oct 31 11:47:01 volumio-beta-storevegen-59 sudo[1816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c7bff9654d2984a81c5720476c2c8ac531869c54" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:08:11 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="00524295c246b7d72f125e4c54b86a88"