-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-03-15 16:42:23 CET. -- Mar 15 16:41:12 volumio2 systemd-timedated[1028]: Changed local time to Sat Mar 15 16:41:12 2025 Mar 15 16:41:12 volumio2 sudo[1026]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:12 volumio2 volumio-time-update[614]: volumio-time-update-util: System time updated successfully. Mar 15 16:41:12 volumio2 systemd[1]: Started Volumio Time Update Utility. Mar 15 16:41:12 volumio2 volumio[1001]: info: ------------------------------------------- Mar 15 16:41:12 volumio2 volumio[1001]: info: ----- Volumio3 ---- Mar 15 16:41:12 volumio2 volumio[1001]: info: ------------------------------------------- Mar 15 16:41:12 volumio2 volumio[1001]: info: ----- System startup ---- Mar 15 16:41:12 volumio2 volumio[1001]: info: ------------------------------------------- Mar 15 16:41:12 volumio2 nmbd[760]: [2025/03/15 16:41:12.970625, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 15 16:41:12 volumio2 systemd[1]: Started Samba NMB Daemon. Mar 15 16:41:12 volumio2 nmbd[760]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 15 16:41:12 volumio2 nmbd[760]: [2025/03/15 16:41:12.991562, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 15 16:41:12 volumio2 nmbd[760]: query_name_response: Multiple (2) responses received for a query on subnet 10.0.0.104 for name WORKGROUP<1d>. Mar 15 16:41:12 volumio2 nmbd[760]: This response was from IP 10.0.0.166, reporting an IP address of 10.0.0.166. Mar 15 16:41:13 volumio2 systemd[1]: Starting Samba Winbind Daemon... Mar 15 16:41:13 volumio2 winbindd[1038]: [2025/03/15 16:41:13.369176, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 15 16:41:13 volumio2 winbindd[1038]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 15 16:41:13 volumio2 systemd[1]: systemd-fsckd.service: Succeeded. Mar 15 16:41:13 volumio2 winbindd[1038]: [2025/03/15 16:41:13.386971, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 15 16:41:13 volumio2 systemd[1]: Started Samba Winbind Daemon. Mar 15 16:41:13 volumio2 winbindd[1038]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 15 16:41:13 volumio2 systemd[1]: Starting Samba SMB Daemon... Mar 15 16:41:13 volumio2 volumio[1001]: info: MYVOLUMIO Environment detected Mar 15 16:41:13 volumio2 volumio[1001]: info: Plugin folders cleanup Mar 15 16:41:13 volumio2 volumio[1001]: info: Scanning into folder /volumio/app/plugins/ Mar 15 16:41:13 volumio2 volumio[1001]: info: Scanning category audio_interface Mar 15 16:41:13 volumio2 volumio[1001]: info: Scanning category miscellanea Mar 15 16:41:13 volumio2 volumio[1001]: info: Scanning category music_service Mar 15 16:41:13 volumio2 volumio[1001]: info: Scanning category plugins.json Mar 15 16:41:13 volumio2 volumio[1001]: info: Scanning category system_controller Mar 15 16:41:13 volumio2 volumio[1001]: info: Scanning category user_interface Mar 15 16:41:13 volumio2 volumio[1001]: info: Scanning into folder /data/plugins/ Mar 15 16:41:13 volumio2 volumio[1001]: info: Scanning category audio_interface Mar 15 16:41:14 volumio2 volumio[1001]: info: Scanning category music_service Mar 15 16:41:14 volumio2 volumio[1001]: info: Scanning category system_controller Mar 15 16:41:14 volumio2 volumio[1001]: info: Scanning category system_hardware Mar 15 16:41:14 volumio2 volumio[1001]: info: Scanning category user_interface Mar 15 16:41:14 volumio2 volumio[1001]: info: Plugin folders cleanup completed Mar 15 16:41:14 volumio2 volumio[1001]: info: ------------------------------------------- Mar 15 16:41:14 volumio2 volumio[1001]: info: ----- Core plugins startup ---- Mar 15 16:41:14 volumio2 volumio[1001]: info: ------------------------------------------- Mar 15 16:41:14 volumio2 volumio[1001]: info: Loading plugins from folder /volumio/app/plugins/ Mar 15 16:41:14 volumio2 volumio[1001]: info: Adding plugin upnp to MyMusic Plugins Mar 15 16:41:14 volumio2 systemd[1]: Started Samba SMB Daemon. Mar 15 16:41:14 volumio2 smbd[1043]: [2025/03/15 16:41:14.010018, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 15 16:41:14 volumio2 volumio[1001]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 15 16:41:14 volumio2 volumio[1001]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 15 16:41:14 volumio2 volumio[1001]: info: Loading plugins from folder /data/plugins/ Mar 15 16:41:14 volumio2 volumio[1001]: info: Loading plugin "system"... Mar 15 16:41:14 volumio2 systemd[1]: Reached target Multi-User System. Mar 15 16:41:14 volumio2 smbd[1043]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 15 16:41:14 volumio2 systemd[1]: Reached target Graphical Interface. Mar 15 16:41:14 volumio2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 15 16:41:14 volumio2 volumio[1001]: info: Loading plugin "appearance"... Mar 15 16:41:14 volumio2 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 15 16:41:14 volumio2 systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 15 16:41:14 volumio2 systemd[1]: Startup finished in 13.417s (kernel) + 33.944s (userspace) = 47.362s. Mar 15 16:41:14 volumio2 sh[465]: timed out Mar 15 16:41:14 volumio2 dhcpcd[511]: timed out Mar 15 16:41:14 volumio2 sh[465]: dhcpcd exited Mar 15 16:41:14 volumio2 dhcpcd[511]: dhcpcd exited Mar 15 16:41:14 volumio2 sh[465]: ifup: failed to bring up eth0 Mar 15 16:41:14 volumio2 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 15 16:41:14 volumio2 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 15 16:41:15 volumio2 volumio[1001]: info: Loading plugin "network"... Mar 15 16:41:15 volumio2 volumio[1001]: info: Refreshing Cached IP Addresses Mar 15 16:41:15 volumio2 sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 16:41:15 volumio2 sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:15 volumio2 sudo[1061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 16:41:15 volumio2 sudo[1061]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:15 volumio2 sudo[1059]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:15 volumio2 sudo[1061]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:15 volumio2 volumio[1001]: info: Loading plugin "services"... Mar 15 16:41:15 volumio2 volumio[1001]: info: Loading plugin "alsa_controller"... Mar 15 16:41:15 volumio2 sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 15 16:41:15 volumio2 sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:15 volumio2 volumio[1001]: warn: Unable to locate the audio output device Allo Katana. Please configure a valid output device. Mar 15 16:41:15 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 16:41:15 volumio2 volumio[1001]: info: Loading plugin "wizard"... Mar 15 16:41:15 volumio2 volumio[1001]: info: Loading plugin "networkfs"... Mar 15 16:41:15 volumio2 volumio[1001]: info: Starting Udev Watcher for removable devices Mar 15 16:41:15 volumio2 sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=1QAY2wsx3edc4rfv!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.189/public /mnt/NAS/WDNAS-3 Mar 15 16:41:15 volumio2 sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:16 volumio2 volumio[1001]: info: Ignoring mount for partition: VOLUMIO2 Mar 15 16:41:16 volumio2 volumio[1001]: info: Ignoring mount for partition: volumio Mar 15 16:41:16 volumio2 volumio[1001]: info: Ignoring mount for partition: volumio_data Mar 15 16:41:16 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 15 16:41:16 volumio2 volumio[1001]: info: Loading plugin "volumio_command_line_client"... Mar 15 16:41:16 volumio2 volumio[1001]: info: Loading plugin "upnp"... Mar 15 16:41:16 volumio2 volumio[1001]: info: [1742053276050] Starting Upmpd Daemon Mar 15 16:41:16 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 15 16:41:16 volumio2 volumio[1001]: info: Loading plugin "my_music"... Mar 15 16:41:16 volumio2 volumio[1001]: info: Loading plugin "mpd"... Mar 15 16:41:16 volumio2 kernel: Key type cifs.spnego registered Mar 15 16:41:16 volumio2 kernel: Key type cifs.idmap registered Mar 15 16:41:16 volumio2 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. Mar 15 16:41:16 volumio2 kernel: CIFS: Attempting to mount //10.0.0.189/public Mar 15 16:41:16 volumio2 kernel: cryptd: max_cpu_qlen set to 1000 Mar 15 16:41:16 volumio2 volumio[1001]: info: Loading plugin "upnp_browser"... Mar 15 16:41:16 volumio2 sudo[1085]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:17 volumio2 volumio[1001]: info: Loading plugin "alarm-clock"... Mar 15 16:41:17 volumio2 volumio[1001]: info: Loading plugin "airplay_emulation"... Mar 15 16:41:17 volumio2 systemd[1]: systemd-hostnamed.service: Succeeded. Mar 15 16:41:17 volumio2 volumio[1001]: info: Starting Shairport Sync Mar 15 16:41:17 volumio2 volumio[1001]: info: Loading plugin "last_100"... Mar 15 16:41:17 volumio2 volumio[1001]: info: Loading plugin "webradio"... Mar 15 16:41:17 volumio2 volumio[1001]: info: Loading plugin "i2s_dacs"... Mar 15 16:41:17 volumio2 volumio[1001]: info: Loading plugin "volumiodiscovery"... Mar 15 16:41:17 volumio2 volumio[1001]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 15 16:41:17 volumio2 volumio[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 15 16:41:17 volumio2 volumio[1001]: *** WARNING *** For more information see Mar 15 16:41:17 volumio2 volumio[1001]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 15 16:41:17 volumio2 volumio[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 15 16:41:17 volumio2 volumio[1001]: *** WARNING *** For more information see Mar 15 16:41:17 volumio2 node[1001]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 15 16:41:17 volumio2 node[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 15 16:41:17 volumio2 node[1001]: *** WARNING *** For more information see Mar 15 16:41:17 volumio2 node[1001]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 15 16:41:17 volumio2 node[1001]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 15 16:41:17 volumio2 node[1001]: *** WARNING *** For more information see Mar 15 16:41:17 volumio2 volumio[1001]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 15 16:41:17 volumio2 volumio[1001]: info: Discovery: Started advertising with name: Volumio2 Mar 15 16:41:17 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 15 16:41:17 volumio2 volumio[1001]: info: Loading plugin "spop"... Mar 15 16:41:18 volumio2 sudo[1074]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:18 volumio2 volumio-remote-updater[606]: [2025-03-15 16:41:18] [connect] Successful connection Mar 15 16:41:19 volumio2 volumio[1001]: info: Loading plugin "youtube2"... Mar 15 16:41:23 volumio2 volumio[1001]: info: Loading plugin "outputs"... Mar 15 16:41:23 volumio2 volumio[1001]: info: Loading plugin "albumart"... Mar 15 16:41:23 volumio2 volumio[1001]: info: Plugin example_plugin is not enabled Mar 15 16:41:23 volumio2 volumio[1001]: info: Loading plugin "inputs"... Mar 15 16:41:23 volumio2 volumio[1001]: info: Loading plugin "updater_comm"... Mar 15 16:41:23 volumio2 volumio[1001]: info: Plugin mpdemulation is not enabled Mar 15 16:41:23 volumio2 volumio[1001]: info: Loading plugin "rest_api"... Mar 15 16:41:23 volumio2 volumio[1001]: info: Loading plugin "websocket"... Mar 15 16:41:23 volumio2 volumio[1001]: info: Starting Socket.io Server version 2.3.0 Mar 15 16:41:23 volumio2 volumio[1001]: info: Loading plugin "fusiondsp"... Mar 15 16:41:23 volumio2 volumio[1001]: Forking 3 albumart workers Mar 15 16:41:24 volumio2 volumio[1001]: info: Applying required configuration parameters for plugin fusiondsp Mar 15 16:41:24 volumio2 volumio[1001]: info: Plugin mpdoutput is not enabled Mar 15 16:41:24 volumio2 volumio[1001]: info: Loading plugin "backup_restore"... Mar 15 16:41:24 volumio2 volumio[1001]: info: Applying required configuration parameters for plugin backup_restore Mar 15 16:41:24 volumio2 volumio[1001]: info: Plugin onkyo_control is not enabled Mar 15 16:41:24 volumio2 volumio[1001]: info: Loading plugin "peppymeterbasic"... Mar 15 16:41:24 volumio2 volumio[1001]: Starting albumart workers Mar 15 16:41:24 volumio2 volumio[1001]: Starting albumart workers Mar 15 16:41:24 volumio2 volumio[1001]: Starting albumart workers Mar 15 16:41:25 volumio2 volumio[1001]: info: Plugin peppyspectrum is not enabled Mar 15 16:41:25 volumio2 volumio[1001]: info: Loading plugin "randomizer"... Mar 15 16:41:25 volumio2 volumio[1001]: info: Applying required configuration parameters for plugin randomizer Mar 15 16:41:26 volumio2 volumio[1001]: info: Loading plugin "touch_display"... Mar 15 16:41:26 volumio2 volumio[1001]: info: Applying required configuration parameters for plugin touch_display Mar 15 16:41:26 volumio2 volumio[1001]: info: Loading i18n strings for locale de Mar 15 16:41:26 volumio2 volumio[1001]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Mar 15 16:41:26 volumio2 volumio[1001]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Mar 15 16:41:26 volumio2 volumio[1001]: Updating browse sources language Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::initPlayerControls Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 16:41:26 volumio2 volumio[1001]: Express server listening on port 3000 Mar 15 16:41:26 volumio2 volumio[1001]: [Metrics] WebUI: 15s 533.41ms Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreStateMachine::resetVolumioState Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreStateMachine::getcurrentVolume Mar 15 16:41:26 volumio2 volumio[1001]: info: CoreCommandRouter::volumioRetrievevolume Mar 15 16:41:27 volumio2 sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 16:41:27 volumio2 sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:27 volumio2 sudo[1201]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:27 volumio2 sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 16:41:27 volumio2 sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:27 volumio2 sudo[1203]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:27 volumio2 volumio[1001]: info: Volumio Network Manager: Network status updated: 2 Mar 15 16:41:27 volumio2 volumio[1001]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104 from 10.0.0.205 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104 from 10.0.0.205 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104 from 10.0.0.205 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104:3000 from 10.0.0.239 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104:3000 from 10.0.0.239 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104 from 10.0.0.205 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104 from 10.0.0.205 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104:3000 from 10.0.0.239 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104:3000 from 10.0.0.239 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104 from 10.0.0.205 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Mar 15 16:41:27 volumio2 volumio[1001]: info: VolumeController:: Volume=undefined Mute =false Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreStateMachine::pushState Mar 15 16:41:27 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::volumioPushState Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreStateMachine::updateTrackBlock Mar 15 16:41:27 volumio2 volumio[1001]: info: CorePlayQueue::getTrackBlock Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::volumioRetrievevolume Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 15 16:41:27 volumio2 volumio[1001]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 15 16:41:27 volumio2 volumio[1001]: info: Reloading queue from file Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:27 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104 from 10.0.0.205 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreStateMachine::setRepeat null single undefined Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreStateMachine::pushState Mar 15 16:41:27 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::volumioPushState Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreStateMachine::setRandom true Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreStateMachine::pushState Mar 15 16:41:27 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::volumioPushState Mar 15 16:41:27 volumio2 volumio[1001]: info: Setting Device type: Raspberry PI Mar 15 16:41:27 volumio2 volumio[1001]: info: VolumeController:: Volume=undefined Mute =false Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreStateMachine::pushState Mar 15 16:41:27 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::volumioPushState Mar 15 16:41:27 volumio2 volumio[1001]: info: Completed loading Core Plugins Mar 15 16:41:27 volumio2 volumio[1001]: info: Preparing to generate the ALSA configuration file Mar 15 16:41:27 volumio2 volumio[1001]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 15 16:41:27 volumio2 volumio[1001]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 15 16:41:27 volumio2 volumio[1001]: info: Reading ALSA contributions from plugins. Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:27 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:27 volumio2 sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 15 16:41:27 volumio2 sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:27 volumio2 volumio[1001]: info: Discovery: adding 6d594ed8-b778-4d61-b85a-ea5fa6038f51 Mar 15 16:41:27 volumio2 volumio[1001]: info: Discovery: Found device Volumio2 Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:27 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:27 volumio2 volumio[1001]: info: Asound.conf file unchanged, so no further update is needed Mar 15 16:41:27 volumio2 volumio[1001]: info: Output device has changed, restarting MPD Mar 15 16:41:27 volumio2 sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 15 16:41:27 volumio2 sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:27 volumio2 systemd[1]: Started UPnP Renderer front-end to MPD. Mar 15 16:41:27 volumio2 volumio[1001]: info: Output device has changed, restarting Shairport Sync Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:27 volumio2 sudo[1220]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:27 volumio2 sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 15 16:41:27 volumio2 sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:27 volumio2 sudo[1216]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:27 volumio2 systemd[1]: Stopping Music Player Daemon... Mar 15 16:41:27 volumio2 volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 16:41:27 volumio2 volumio[1001]: info: ___________ START PLUGINS ___________ Mar 15 16:41:27 volumio2 volumio[1001]: info: ControllerMpd::onStart: Initializing MPD Mar 15 16:41:27 volumio2 volumio[1001]: info: Creating MPD Configuration file Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 16:41:27 volumio2 volumio[1001]: info: [1742053287835] CoreMusicLibrary::Adding element Medienserver Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 16:41:27 volumio2 sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 15 16:41:27 volumio2 sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:27 volumio2 sudo[1229]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:27 volumio2 sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 15 16:41:27 volumio2 sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:27 volumio2 systemd[1]: mpd.service: Succeeded. Mar 15 16:41:27 volumio2 systemd[1]: Stopped Music Player Daemon. Mar 15 16:41:27 volumio2 volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:27 volumio2 volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 16:41:27 volumio2 volumio[1001]: info: [1742053287985] CoreMusicLibrary::Adding element Last_100 Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 16:41:27 volumio2 volumio[1001]: info: [1742053287989] CoreMusicLibrary::Adding element Webradio Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 16:41:27 volumio2 systemd[1]: Starting Music Player Daemon... Mar 15 16:41:27 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 16:41:28 volumio2 volumio[1001]: info: Initializing BBC Radios Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:28 volumio2 volumio[1001]: info: Creating Spotify config file Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:28 volumio2 sudo[1237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 15 16:41:28 volumio2 sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:28 volumio2 sudo[1237]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 16:41:28 volumio2 volumio[1001]: info: [1742053288202] CoreMusicLibrary::Adding element YouTube2 Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 16:41:28 volumio2 volumio[1001]: Cannot find translation for source YouTube2 Mar 15 16:41:28 volumio2 volumio[1001]: info: Loading i18n strings for locale de Mar 15 16:41:28 volumio2 volumio[1001]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Mar 15 16:41:28 volumio2 volumio[1001]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:28 volumio2 volumio[1001]: info: FusionDsp - mixtype--------------------- undefined Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 16:41:28 volumio2 volumio[1001]: info: [1742053288282] CoreMusicLibrary::Adding element Randomizer Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 16:41:28 volumio2 volumio[1001]: Cannot find translation for source YouTube2 Mar 15 16:41:28 volumio2 volumio[1001]: Cannot find translation for source Randomizer Mar 15 16:41:28 volumio2 volumio[1001]: info: Loading i18n strings for locale de Mar 15 16:41:28 volumio2 volumio[1001]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Mar 15 16:41:28 volumio2 volumio[1001]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Mar 15 16:41:28 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 16:41:28 volumio2 volumio[1001]: info: Volumio Calling Home Mar 15 16:41:28 volumio2 volumio[1001]: info: Preparing to generate the ALSA configuration file Mar 15 16:41:28 volumio2 sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 15 16:41:28 volumio2 sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:28 volumio2 sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 15 16:41:28 volumio2 sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:28 volumio2 sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 15 16:41:28 volumio2 sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:28 volumio2 volumio[1001]: info: Discovery: adding 3ea5a15f-55c7-44b6-ae80-cbe98c591a6e Mar 15 16:41:28 volumio2 volumio[1001]: info: Discovery: Found device Volumio-SMSL Mar 15 16:41:28 volumio2 volumio[1001]: info: Discovery: Connecting to remote: 10.0.0.239 Mar 15 16:41:28 volumio2 systemd[1]: Reloading. Mar 15 16:41:28 volumio2 volumio[1001]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 15 16:41:28 volumio2 volumio[1001]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 15 16:41:28 volumio2 volumio[1001]: info: Reading ALSA contributions from plugins. Mar 15 16:41:28 volumio2 volumio[1001]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Mar 15 16:41:28 volumio2 volumio[1001]: info: MPD Permissions set Mar 15 16:41:28 volumio2 volumio[1001]: info: MPD Permissions set Mar 15 16:41:28 volumio2 volumio[1001]: info: Upmpdcli Daemon Started Mar 15 16:41:28 volumio2 volumio[1001]: info: Volumio called home Mar 15 16:41:28 volumio2 volumio[1001]: info: Spotify config file written Mar 15 16:41:28 volumio2 sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 15 16:41:28 volumio2 sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:28 volumio2 volumio[1001]: info: Discovery: Connected to remote: 10.0.0.239 Mar 15 16:41:29 volumio2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 15 16:41:29 volumio2 volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 15 16:41:29 volumio2 volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 15 16:41:29 volumio2 volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: No need to fix Spotify hosts Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:29 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:29 volumio2 sudo[1263]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:29 volumio2 sudo[1257]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:29 volumio2 systemd[1]: Reloading. Mar 15 16:41:30 volumio2 volumio[1001]: info: touch_display: systemctl daemon-reload succeeded. Mar 15 16:41:30 volumio2 mpd[1252]: Mar 15 16:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 15 16:41:30 volumio2 sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 15 16:41:30 volumio2 sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:30 volumio2 volumio[1001]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 15 16:41:30 volumio2 sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 15 16:41:30 volumio2 sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:30 volumio2 sudo[1306]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:30 volumio2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 15 16:41:30 volumio2 volumio[1001]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 15 16:41:30 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1317, instance 1, run: true Mar 15 16:41:30 volumio2 volumio[1001]: info: camilladsp service started and running in background, instance 1 Mar 15 16:41:30 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:30 volumio2 volumio[1001]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Mar 15 16:41:30 volumio2 volumio[1001]: cannot open device 'volumioHw': No such device Mar 15 16:41:30 volumio2 volumio[1001]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Mar 15 16:41:30 volumio2 volumio[1001]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Mar 15 16:41:30 volumio2 volumio[1001]: cannot open device 'volumioHw': No such device Mar 15 16:41:30 volumio2 volumio[1001]: info: FusionDsp loaded Mar 15 16:41:30 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 16:41:30 volumio2 sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 15 16:41:30 volumio2 sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:30 volumio2 volumio[1001]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 15 16:41:30 volumio2 volumio[1001]: info: Adding Signal Path Element [object Object] Mar 15 16:41:30 volumio2 volumio[1001]: info: Adding fusiondspeq DSP Signal Path Element Mar 15 16:41:30 volumio2 volumio[1001]: info: FusionDsp - ---- installed callbackRead Mar 15 16:41:30 volumio2 volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 15 16:41:30 volumio2 volumio[1001]: info: Starting Shairport Sync Mar 15 16:41:30 volumio2 volumio[1001]: info: Starting Shairport Sync Mar 15 16:41:30 volumio2 sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 16:41:30 volumio2 sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:30 volumio2 volumio[1001]: info: Starting Shairport Sync Mar 15 16:41:30 volumio2 sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 16:41:30 volumio2 sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:30 volumio2 volumio[1001]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 15 16:41:30 volumio2 volumio[1001]: SPOTIFY: BQBjFbWezGeY9ByBnP8MWq3WZ627cNVRV1s1aXTJEcubclS_nB6CCKEElOZAAb-MU6NW3lEVQ3-m6MBJnWd0d5TkdrColXpcaDEOIs2fCMXpJBf7DTFjFrwxuoFE7IrHcJsnD14m4nYpIIt1x1H-6-Tw2JJYdbeIxrREfClTYo-aFd8PqrKeyT-NxoD2GCt_I9ZaVjt3D5W_2fKLvwxu3y22KvFpkI5CCmpf61KLn_zrKKRAWo9aU7JQPFv2U7SQUcHBPENE2JpwhJcNOznjnujJ05QNjLIAWagljio Mar 15 16:41:30 volumio2 volumio[1001]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 15 16:41:30 volumio2 volumio[1001]: info: New Spotify access token = BQBjFbWezGeY9ByBnP8MWq3WZ627cNVRV1s1aXTJEcubclS_nB6CCKEElOZAAb-MU6NW3lEVQ3-m6MBJnWd0d5TkdrColXpcaDEOIs2fCMXpJBf7DTFjFrwxuoFE7IrHcJsnD14m4nYpIIt1x1H-6-Tw2JJYdbeIxrREfClTYo-aFd8PqrKeyT-NxoD2GCt_I9ZaVjt3D5W_2fKLvwxu3y22KvFpkI5CCmpf61KLn_zrKKRAWo9aU7JQPFv2U7SQUcHBPENE2JpwhJcNOznjnujJ05QNjLIAWagljio Mar 15 16:41:30 volumio2 volumio[1001]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 15 16:41:30 volumio2 sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 16:41:30 volumio2 sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:30 volumio2 volumio[1001]: Upnp client error: Error: This socket has been ended by the other party Mar 15 16:41:30 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:30 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:30 volumio2 volumio[1001]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 15 16:41:30 volumio2 volumio[1001]: error: FusionDsp - WebSocket error: [object Object] Mar 15 16:41:30 volumio2 systemd[1]: Started Music Player Daemon. Mar 15 16:41:30 volumio2 sudo[1231]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:30 volumio2 sudo[1259]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:30 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1342, instance 1, run: true Mar 15 16:41:30 volumio2 volumio[1001]: info: Asound.conf file unchanged, so no further update is needed Mar 15 16:41:30 volumio2 volumio[1001]: info: Output device has changed, restarting MPD Mar 15 16:41:30 volumio2 sudo[1222]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:30 volumio2 volumio[1001]: info: Output device has changed, restarting Shairport Sync Mar 15 16:41:30 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:30 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:30 volumio2 sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 15 16:41:30 volumio2 sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:30 volumio2 sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 15 16:41:30 volumio2 sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:30 volumio2 sudo[1350]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:30 volumio2 volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 16:41:30 volumio2 volumio[1001]: info: Preparing to generate the ALSA configuration file Mar 15 16:41:30 volumio2 systemd[1]: Stopping Music Player Daemon... Mar 15 16:41:30 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 15 16:41:30 volumio2 volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Mar 15 16:41:30 volumio2 volumio[1001]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 15 16:41:30 volumio2 volumio[1001]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 15 16:41:30 volumio2 volumio[1001]: info: Reading ALSA contributions from plugins. Mar 15 16:41:31 volumio2 systemd[1]: Started FusionDsp Daemon. Mar 15 16:41:31 volumio2 sudo[1321]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:31 volumio2 systemd[1]: Started Volumio Kiosk. Mar 15 16:41:31 volumio2 volumio[1001]: info: MPD Permissions set Mar 15 16:41:31 volumio2 sudo[1304]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:31 volumio2 systemd[1]: Started go-librespot Daemon. Mar 15 16:41:31 volumio2 sudo[1279]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:31 volumio2 go-librespot[1360]: go-librespot daemon starting... Mar 15 16:41:31 volumio2 systemd[1]: shairport-sync.service: Succeeded. Mar 15 16:41:31 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 15 16:41:31 volumio2 systemd[1]: mpd.service: Succeeded. Mar 15 16:41:31 volumio2 volumio[1001]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 15 16:41:31 volumio2 systemd[1]: Stopped Music Player Daemon. Mar 15 16:41:31 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1366, instance 1, run: true Mar 15 16:41:31 volumio2 volumio[1001]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 15 16:41:31 volumio2 volumio[1001]: info: touch_display: Volumio Kiosk started. Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:31 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:31 volumio2 systemd[1]: Starting Music Player Daemon... Mar 15 16:41:31 volumio2 volumio[1001]: info: Completed starting Core Plugins Mar 15 16:41:31 volumio2 volumio[1001]: info: ------------------------------------------- Mar 15 16:41:31 volumio2 volumio[1001]: info: ----- MyVolumio plugins startup ---- Mar 15 16:41:31 volumio2 volumio[1001]: info: ------------------------------------------- Mar 15 16:41:31 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 15 16:41:31 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 15 16:41:31 volumio2 sudo[1333]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:31 volumio2 sudo[1336]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:31 volumio2 sudo[1326]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:31 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:31 volumio2 volumio[1001]: info: Shairport-Sync Started Mar 15 16:41:31 volumio2 volumio[1001]: Error adding Membership: Error: addMembership EINVAL Mar 15 16:41:31 volumio2 volumio[1001]: error: Cannot initialize MPD Connection: MPD is not running Mar 15 16:41:31 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1398, instance 1, run: true Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:31 volumio2 sudo[1371]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 15 16:41:31 volumio2 sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:31 volumio2 sudo[1371]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:31 volumio2 volumio[1001]: info: Shairport-Sync Started Mar 15 16:41:31 volumio2 volumio[1001]: info: Shairport-Sync Started Mar 15 16:41:31 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104 from 10.0.0.205 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 17 Mar 15 16:41:31 volumio2 volumio[1001]: info: touch_display: X display number found: Mar 15 16:41:31 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1417, instance 1, run: true Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 16:41:31 volumio2 volumio[1001]: info: touch_display: X display number found: 0 Mar 15 16:41:31 volumio2 volumio[1001]: info: Starting Shairport Sync Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:31 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 15 16:41:31 volumio2 volumio[1001]: info: Received Get System Info Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 16:41:31 volumio2 volumio[1001]: info: Discovery: Getting this device information Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:31 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 16:41:31 volumio2 sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:31 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:31 volumio2 sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:31 volumio2 startx[1359]: X.Org X Server 1.20.4 Mar 15 16:41:31 volumio2 startx[1359]: X Protocol Version 11, Revision 0 Mar 15 16:41:31 volumio2 startx[1359]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Mar 15 16:41:31 volumio2 startx[1359]: Current Operating System: Linux volumio2 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Mar 15 16:41:31 volumio2 startx[1359]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=576 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=fb80cb44-b4d3-4b36-be35-613cba0a09fb imgfile=/volumio_current.sqsh bootpart=UUID=51B1-040D datapart=UUID=2899bccc-8620-4c7a-b56a-43fd2f7a836a uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 15 16:41:31 volumio2 startx[1359]: Build Date: 04 April 2023 07:50:56AM Mar 15 16:41:31 volumio2 startx[1359]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Mar 15 16:41:31 volumio2 startx[1359]: Current version of pixman: 0.36.0 Mar 15 16:41:31 volumio2 startx[1359]: Before reporting problems, check http://wiki.x.org Mar 15 16:41:31 volumio2 startx[1359]: to make sure that you have the latest version. Mar 15 16:41:31 volumio2 startx[1359]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 15 16:41:31 volumio2 startx[1359]: (++) from command line, (!!) notice, (II) informational, Mar 15 16:41:31 volumio2 startx[1359]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 15 16:41:31 volumio2 startx[1359]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Mar 15 16:41:31 2025 Mar 15 16:41:31 volumio2 volumio[1001]: info: Listing playlists Mar 15 16:41:31 volumio2 startx[1359]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 15 16:41:31 volumio2 startx[1359]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 15 16:41:31 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 15 16:41:31 volumio2 systemd[1]: shairport-sync.service: Succeeded. Mar 15 16:41:31 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetQueue Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreStateMachine::getQueue Mar 15 16:41:31 volumio2 volumio[1001]: info: CorePlayQueue::getQueue Mar 15 16:41:31 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 15 16:41:31 volumio2 sudo[1425]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:31 volumio2 volumio[1001]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 15 16:41:31 volumio2 volumio[1001]: info: Shairport-Sync Started Mar 15 16:41:31 volumio2 volumio[1001]: info: Asound.conf file unchanged, so no further update is needed Mar 15 16:41:31 volumio2 volumio[1001]: info: Output device has changed, restarting MPD Mar 15 16:41:31 volumio2 sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 15 16:41:31 volumio2 sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:31 volumio2 volumio[1001]: info: Output device has changed, restarting Shairport Sync Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:31 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:31 volumio2 sudo[1433]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:31 volumio2 go-librespot[1360]: time="2025-03-15T16:41:31+01:00" level=info msg="running go-librespot 0.2.0" Mar 15 16:41:31 volumio2 go-librespot[1360]: time="2025-03-15T16:41:31+01:00" level=debug msg="app state loaded" Mar 15 16:41:31 volumio2 go-librespot[1360]: time="2025-03-15T16:41:31+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 15 16:41:31 volumio2 sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 15 16:41:31 volumio2 sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:31 volumio2 volumio[1001]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 16:41:31 volumio2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 15 16:41:31 volumio2 systemd[1]: mpd.service: Succeeded. Mar 15 16:41:31 volumio2 systemd[1]: Stopped Music Player Daemon. Mar 15 16:41:32 volumio2 systemd[1]: Starting Music Player Daemon... Mar 15 16:41:32 volumio2 volumio[1001]: info: touch_display: X display number found: 0 Mar 15 16:41:32 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1447, instance 1, run: true Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 16:41:32 volumio2 volumio[1001]: info: MPD Permissions set Mar 15 16:41:32 volumio2 sudo[1442]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 15 16:41:32 volumio2 sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:32 volumio2 sudo[1442]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:32 volumio2 volumio[1001]: info: VolumeController::SetAlsaVolume0 Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreStateMachine::pushState Mar 15 16:41:32 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::volumioPushState Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:32 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:41:32 volumio2 volumio[1001]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 15 16:41:32 volumio2 volumio[1001]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 15 16:41:32 volumio2 sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 15 16:41:32 volumio2 sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:32 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1463, instance 1, run: true Mar 15 16:41:32 volumio2 volumio[1001]: info: FusionDsp - no preset used Mar 15 16:41:32 volumio2 sudo[1462]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:32 volumio2 go-librespot[1360]: time="2025-03-15T16:41:32+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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 15 16:41:32 volumio2 go-librespot[1360]: time="2025-03-15T16:41:32+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 15 16:41:32 volumio2 go-librespot[1360]: time="2025-03-15T16:41:32+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 15 16:41:32 volumio2 go-librespot[1360]: time="2025-03-15T16:41:32+01:00" level=info msg="zeroconf server listening on port 44057" Mar 15 16:41:32 volumio2 volumio[1001]: info: FusionDsp - undefined Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="obtained new client token: AABFAs4j2m680VeA3kHKyD9FSEigw/SzWcJEQ8usobyHpOF1P0LeOTK53gxlz3BJztLvWx+Idsr0W0GWg9eQoFaN/CLHTqpfpxFgZF1d9ErPswVrCwgSGCg01I9NzxD7nIL276hxb8ic+uG74+p+Qjl//2eap3xaYMjvC0Hm20rySvNla2n7XdM7Lxfp9e9z2R+KXnwRdor6X4Euuy8levslzmo80hggyCc5FIWv9t7O9JMuBkzDsbEejtbd" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="completed keyexchange" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="completed challenge" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=info msg="authenticated AP as sdi3ff4vigkoeya8ibshzxneo" Mar 15 16:41:33 volumio2 volumio[1001]: verbose: New Socket.io Connection to 10.0.0.104 from 10.0.0.205 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 18 Mar 15 16:41:33 volumio2 volumio[1001]: info: peppymeterbasic Daemon Stop Mar 15 16:41:33 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1472, instance 1, run: true Mar 15 16:41:33 volumio2 volumio[1001]: info: Starting Shairport Sync Mar 15 16:41:33 volumio2 volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Mar 15 16:41:33 volumio2 volumio[1001]: SPOTIFY: User informations: {"country":"AT","display_name":"Walter.hoerner","email":"walter.hoerner@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/sdi3ff4vigkoeya8ibshzxneo"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/sdi3ff4vigkoeya8ibshzxneo","id":"sdi3ff4vigkoeya8ibshzxneo","images":[],"product":"premium","type":"user","uri":"spotify:user:sdi3ff4vigkoeya8ibshzxneo"} Mar 15 16:41:33 volumio2 volumio[1001]: info: Spotify Successfully logged in Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 16:41:33 volumio2 volumio[1001]: info: [1742053293442] CoreMusicLibrary::Adding element Spotify Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 16:41:33 volumio2 volumio[1001]: Cannot find translation for source YouTube2 Mar 15 16:41:33 volumio2 volumio[1001]: Cannot find translation for source Randomizer Mar 15 16:41:33 volumio2 volumio[1001]: Cannot find translation for source Spotify Mar 15 16:41:33 volumio2 sudo[1480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 16:41:33 volumio2 sudo[1480]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=info msg="authenticated Login5 as sdi3ff4vigkoeya8ibshzxneo" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="initializing zeroconf session, username: sdi3ff4vigkoeya8ibshzxneo" Mar 15 16:41:33 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 15 16:41:33 volumio2 systemd[1]: shairport-sync.service: Succeeded. Mar 15 16:41:33 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 15 16:41:33 volumio2 volumio-remote-updater[606]: [2025-03-15 16:41:33] [connect] Successful connection Mar 15 16:41:33 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 15 16:41:33 volumio2 sudo[1480]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="dealer connection opened" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=trace msg="starting accesspoint recv loop" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=trace msg="starting dealer recv loop" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=trace msg="received accesspoint ping" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=debug msg="received connection id: M2E0NDZkNWEtYmJiMy00ZWZiLWIyYWEtMDUwMWRiZDJjNWViK2RlYWxlcit0Y3A6Ly8wYWNhNTgxYi5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArMUNCNkVGRjVGODdCRTM0ODEwQjg4RjI5RUQ2NkFDQzI2MzZEMDkyMkY4MUJFOUE0NkU0NDU3REMzODQwRjUyOA==" Mar 15 16:41:33 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1491, instance 1, run: true Mar 15 16:41:33 volumio2 go-librespot[1360]: time="2025-03-15T16:41:33+01:00" level=trace msg="received accesspoint pong ack" Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:33 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 15 16:41:33 volumio2 volumio[1001]: info: Received Get System Info Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 16:41:33 volumio2 volumio[1001]: info: Discovery: Getting this device information Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:33 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 16:41:33 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:33 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:33 volumio2 volumio[1001]: info: Listing playlists Mar 15 16:41:33 volumio2 volumio[1001]: 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: 20 Mar 15 16:41:33 volumio2 volumio-remote-updater[606]: [2025-03-15 16:41:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742053293 101 Mar 15 16:41:33 volumio2 volumio[1001]: info: Shairport-Sync Started Mar 15 16:41:33 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1502, instance 1, run: true Mar 15 16:41:34 volumio2 go-librespot[1360]: time="2025-03-15T16:41:34+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 15 16:41:34 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1509, instance 1, run: true Mar 15 16:41:34 volumio2 volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 16:41:34 volumio2 volumio[1001]: info: go-librespot daemon successfully initialized Mar 15 16:41:34 volumio2 volumio[1001]: info: FusionDsp - no preset used Mar 15 16:41:34 volumio2 mpd[1455]: Mar 15 16:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 15 16:41:34 volumio2 systemd[1]: Started Music Player Daemon. Mar 15 16:41:34 volumio2 sudo[1352]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:34 volumio2 sudo[1436]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:34 volumio2 volumio[1001]: info: FusionDsp - undefined Mar 15 16:41:34 volumio2 volumio[1001]: error: MPD error: The expression evaluated to a falsy value: Mar 15 16:41:34 volumio2 volumio[1001]: assert.ok(self.idling) Mar 15 16:41:34 volumio2 volumio[1001]: error: The expression evaluated to a falsy value: Mar 15 16:41:34 volumio2 volumio[1001]: assert.ok(self.idling) Mar 15 16:41:34 volumio2 volumio[1001]: Upnp client error: Error: This socket has been ended by the other party Mar 15 16:41:34 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:34 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:34 volumio2 volumio[1001]: error: updateQueue error: null Mar 15 16:41:34 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1538, instance 1, run: true Mar 15 16:41:34 volumio2 volumio[1001]: error: MPD error: The expression evaluated to a falsy value: Mar 15 16:41:34 volumio2 volumio[1001]: assert.ok(self.idling) Mar 15 16:41:34 volumio2 volumio[1001]: error: The expression evaluated to a falsy value: Mar 15 16:41:34 volumio2 volumio[1001]: assert.ok(self.idling) Mar 15 16:41:34 volumio2 volumio[1001]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 15 16:41:34 volumio2 volumio[1001]: error: updateQueue error: null Mar 15 16:41:34 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1545, instance 1, run: true Mar 15 16:41:34 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1552, instance 1, run: true Mar 15 16:41:34 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1559, instance 1, run: true Mar 15 16:41:34 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1566, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1573, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1580, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1594, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1603, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1617, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1633, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1642, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1649, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1656, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1663, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1672, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1683, instance 1, run: true Mar 15 16:41:35 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1700, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1711, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1718, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1725, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1732, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1739, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1746, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1756, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1768, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1780, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1787, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1794, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1801, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1808, instance 1, run: true Mar 15 16:41:36 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1815, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1822, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1833, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1840, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: Initializing connection to go-librespot Websocket Mar 15 16:41:37 volumio2 go-librespot[1360]: time="2025-03-15T16:41:37+01:00" level=debug msg="new websocket client" Mar 15 16:41:37 volumio2 volumio[1001]: info: Connection to go-librespot Websocket established Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1847, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1854, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1861, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1868, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1875, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1882, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1889, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1896, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1903, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1910, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1917, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1937, instance 1, run: true Mar 15 16:41:37 volumio2 volumio[1218]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1946, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1953, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1960, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1967, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1974, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1981, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1988, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 1995, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2004, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2011, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2022, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2030, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2040, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2047, instance 1, run: true Mar 15 16:41:38 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2054, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2061, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2068, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2078, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2085, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2092, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2099, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2106, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1218]: ........++++ Mar 15 16:41:39 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2113, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2120, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2127, instance 1, run: true Mar 15 16:41:39 volumio2 volumio[1001]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 15 16:41:40 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2142, instance 1, run: true Mar 15 16:41:40 volumio2 volumio[1001]: info: Getting Spotify volume Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 15 16:41:40 volumio2 volumio[1001]: info: Adding plugin bluetooth to MyMusic Plugins Mar 15 16:41:40 volumio2 volumio[1001]: info: Adding plugin multiroom to MyMusic Plugins Mar 15 16:41:40 volumio2 volumio[1001]: info: Adding plugin metavolumio to MyMusic Plugins Mar 15 16:41:40 volumio2 volumio[1001]: info: Adding plugin cd_controller to MyMusic Plugins Mar 15 16:41:40 volumio2 volumio[1001]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 15 16:41:40 volumio2 volumio[1001]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 15 16:41:40 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 15 16:41:41 volumio2 volumio[1218]: ...............++++ Mar 15 16:41:41 volumio2 volumio[1218]: e is 65537 (0x010001) Mar 15 16:41:41 volumio2 volumio[1218]: writing RSA key Mar 15 16:41:42 volumio2 systemd[1]: systemd-timedated.service: Succeeded. Mar 15 16:41:42 volumio2 volumio[1001]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 15 16:41:42 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 15 16:41:42 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:42 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:42 volumio2 volumio[1001]: info: Starting MyVolumio Remote Streaming Endpoints Mar 15 16:41:42 volumio2 volumio[1001]: info: MyVolumio login type: Token Mar 15 16:41:42 volumio2 volumio[1001]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 15 16:41:42 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 15 16:41:44 volumio2 volumio[1001]: info: Starting Streaming Service Transparent Proxy Mar 15 16:41:44 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 15 16:41:44 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 15 16:41:44 volumio2 volumio[1001]: info: Streaming services startup Mar 15 16:41:44 volumio2 volumio[1001]: info: Starting Streaming Daemon Mar 15 16:41:44 volumio2 sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 15 16:41:44 volumio2 sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:44 volumio2 volumio[1001]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 15 16:41:44 volumio2 sudo[2223]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:44 volumio2 volumio[1001]: info: Spotify volume: 100 Mar 15 16:41:44 volumio2 volumio[1001]: error: Cannot start Volumio Streaming Daemon Mar 15 16:41:44 volumio2 volumio[1001]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 15 16:41:44 volumio2 volumio[1001]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 15 16:41:44 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2241, instance 1, run: true Mar 15 16:41:44 volumio2 volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Mar 15 16:41:44 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:44 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:44 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2248, instance 1, run: true Mar 15 16:41:44 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2255, instance 1, run: true Mar 15 16:41:44 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2262, instance 1, run: true Mar 15 16:41:45 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2269, instance 1, run: true Mar 15 16:41:45 volumio2 volumio[1001]: error: MyVolumio Custom Token format not valid, refreshing it Mar 15 16:41:45 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2276, instance 1, run: true Mar 15 16:41:45 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2287, instance 1, run: true Mar 15 16:41:45 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2299, instance 1, run: true Mar 15 16:41:45 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2308, instance 1, run: true Mar 15 16:41:45 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2335, instance 1, run: true Mar 15 16:41:45 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2369, instance 1, run: true Mar 15 16:41:45 volumio2 volumio[1001]: info: MyVolumio login type: Token Mar 15 16:41:45 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2384, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2404, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2412, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2419, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2426, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2433, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2440, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2447, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2460, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2467, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2474, instance 1, run: true Mar 15 16:41:46 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2481, instance 1, run: true Mar 15 16:41:47 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2488, instance 1, run: true Mar 15 16:41:47 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2498, instance 1, run: true Mar 15 16:41:47 volumio2 volumio[1001]: info: MyVolumio token set successfully Mar 15 16:41:47 volumio2 volumio[1001]: STREAMING PROXY: Starting server on port 3245 Mar 15 16:41:47 volumio2 volumio[1001]: Node JS runtime: 14 Mar 15 16:41:47 volumio2 volumio[1001]: info: MYVOLUMIO: Adding device Mar 15 16:41:47 volumio2 volumio[1001]: info: MYVOLUMIO: Evaluating Server Mar 15 16:41:47 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2505, instance 1, run: true Mar 15 16:41:47 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2512, instance 1, run: true Mar 15 16:41:47 volumio2 volumio[1001]: info: MyVolumio status changed Mar 15 16:41:47 volumio2 volumio[1001]: info: Streaming services startup Mar 15 16:41:47 volumio2 volumio[1001]: info: Starting Streaming Daemon Mar 15 16:41:47 volumio2 volumio[1001]: info: Removing browser output: myVolumio user plan is not superstar Mar 15 16:41:47 volumio2 volumio[1001]: info: Removing audio output: Mar 15 16:41:47 volumio2 volumio[1001]: info: Stoppping Tunnel 1 Mar 15 16:41:47 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2538, instance 1, run: true Mar 15 16:41:47 volumio2 sudo[2537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 15 16:41:47 volumio2 sudo[2535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 15 16:41:47 volumio2 sudo[2535]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:47 volumio2 sudo[2537]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2547, instance 1, run: true Mar 15 16:41:48 volumio2 sudo[2535]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:48 volumio2 volumio[1001]: error: Cannot start Volumio Streaming Daemon Mar 15 16:41:48 volumio2 volumio[1001]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 15 16:41:48 volumio2 volumio[1001]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 15 16:41:48 volumio2 sudo[2537]: pam_unix(sudo:session): session closed for user root Mar 15 16:41:48 volumio2 volumio[1001]: info: Remote SSH Stopped Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2554, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2561, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: Setting Geolocation for MyVolumio to eu4 Mar 15 16:41:48 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:48 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2568, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2575, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2582, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2589, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2596, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2603, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2610, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2617, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2624, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2631, instance 1, run: true Mar 15 16:41:48 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2638, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2645, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2652, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2659, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: Updating MyVolumio device info Mar 15 16:41:49 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:49 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:49 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2666, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2673, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2680, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2687, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2694, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2701, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2708, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2715, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2722, instance 1, run: true Mar 15 16:41:49 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2729, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2736, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2743, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2750, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2757, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2764, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2771, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2778, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2785, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2792, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2799, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2806, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2815, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2823, instance 1, run: true Mar 15 16:41:50 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2830, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2837, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2844, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2851, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2858, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2865, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2872, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2879, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2886, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2893, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2900, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2907, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2914, instance 1, run: true Mar 15 16:41:51 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 15 16:41:51 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2921, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2928, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2935, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2942, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2949, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2956, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2963, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2970, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2977, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2984, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: info: MYVOLUMIO: Adding device Mar 15 16:41:52 volumio2 volumio[1001]: info: MYVOLUMIO: Evaluating Server Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2991, instance 1, run: true Mar 15 16:41:52 volumio2 ntpd[778]: Soliciting pool server 46.102.157.67 Mar 15 16:41:52 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 2998, instance 1, run: true Mar 15 16:41:52 volumio2 volumio[1001]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Mar 15 16:41:53 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3020, instance 1, run: true Mar 15 16:41:53 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3027, instance 1, run: true Mar 15 16:41:53 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3034, instance 1, run: true Mar 15 16:41:53 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3041, instance 1, run: true Mar 15 16:41:53 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3048, instance 1, run: true Mar 15 16:41:53 volumio2 volumio[1001]: info: Setting Geolocation for MyVolumio to eu4 Mar 15 16:41:53 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:53 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:53 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:53 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3055, instance 1, run: true Mar 15 16:41:53 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3062, instance 1, run: true Mar 15 16:41:53 volumio2 ntpd[778]: Soliciting pool server 91.206.8.70 Mar 15 16:41:53 volumio2 ntpd[778]: Soliciting pool server 37.252.189.117 Mar 15 16:41:53 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3069, instance 1, run: true Mar 15 16:41:53 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 16:41:53 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3076, instance 1, run: true Mar 15 16:41:53 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3083, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:54 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 15 16:41:54 volumio2 volumio[1001]: info: Received Get System Info Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 16:41:54 volumio2 volumio[1001]: info: Discovery: Getting this device information Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:54 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:54 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:54 volumio2 volumio[1001]: info: Listing playlists Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3090, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3097, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3104, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3111, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3118, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: info: Updating MyVolumio device info Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:54 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3125, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3132, instance 1, run: true Mar 15 16:41:54 volumio2 ntpd[778]: Soliciting pool server 86.59.113.124 Mar 15 16:41:54 volumio2 ntpd[778]: Soliciting pool server 91.206.8.36 Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3139, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3146, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3153, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3160, instance 1, run: true Mar 15 16:41:54 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3167, instance 1, run: true Mar 15 16:41:55 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3174, instance 1, run: true Mar 15 16:41:55 volumio2 volumio[1001]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 15 16:41:55 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3181, instance 1, run: true Mar 15 16:41:55 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3188, instance 1, run: true Mar 15 16:41:55 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3195, instance 1, run: true Mar 15 16:41:55 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3202, instance 1, run: true Mar 15 16:41:55 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3209, instance 1, run: true Mar 15 16:41:55 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3216, instance 1, run: true Mar 15 16:41:55 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3223, instance 1, run: true Mar 15 16:41:55 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3230, instance 1, run: true Mar 15 16:41:55 volumio2 ntpd[778]: Soliciting pool server 178.251.64.52 Mar 15 16:41:55 volumio2 ntpd[778]: Soliciting pool server 144.76.197.108 Mar 15 16:41:55 volumio2 volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 16:41:55 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 16:41:55 volumio2 ntpd[778]: Soliciting pool server 86.59.80.170 Mar 15 16:41:55 volumio2 volumio[1001]: info: Received Get System Info Mar 15 16:41:55 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 16:41:55 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 16:41:55 volumio2 volumio[1001]: info: Discovery: Getting this device information Mar 15 16:41:55 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:55 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:55 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 16:41:55 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3237, instance 1, run: true Mar 15 16:41:55 volumio2 volumio[1001]: info: FusionDsp - no preset used Mar 15 16:41:56 volumio2 volumio[1001]: info: FusionDsp - undefined Mar 15 16:41:56 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3244, instance 1, run: true Mar 15 16:41:56 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3265, instance 1, run: true Mar 15 16:41:56 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3272, instance 1, run: true Mar 15 16:41:56 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3279, instance 1, run: true Mar 15 16:41:56 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 15 16:41:56 volumio2 ntpd[778]: Soliciting pool server 162.159.200.1 Mar 15 16:41:56 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3286, instance 1, run: true Mar 15 16:41:56 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3293, instance 1, run: true Mar 15 16:41:56 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3300, instance 1, run: true Mar 15 16:41:56 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3307, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3314, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3321, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3328, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Mar 15 16:41:57 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 15 16:41:57 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:57 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3335, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3342, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3349, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3356, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3363, instance 1, run: true Mar 15 16:41:57 volumio2 ntpd[778]: Soliciting pool server 162.159.200.123 Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3370, instance 1, run: true Mar 15 16:41:57 volumio2 ntpd[778]: Soliciting pool server 91.206.8.34 Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3377, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3384, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3391, instance 1, run: true Mar 15 16:41:57 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3398, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3405, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3412, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3419, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3426, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 16:41:58 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:41:58 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3435, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3442, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 15 16:41:58 volumio2 volumio-remote-updater[606]: No test mode Mar 15 16:41:58 volumio2 volumio-remote-updater[606]: No alpha test mode Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3449, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3457, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3464, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 15 16:41:58 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 15 16:41:58 volumio2 ntpd[778]: Soliciting pool server 78.41.116.149 Mar 15 16:41:58 volumio2 ntpd[778]: Soliciting pool server 2606:4700:f1::1 Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3471, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3478, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3485, instance 1, run: true Mar 15 16:41:58 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3492, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3499, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3506, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3513, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 16:41:59 volumio2 volumio[1001]: info: Received Get System Info Mar 15 16:41:59 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 16:41:59 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 16:41:59 volumio2 volumio[1001]: info: Discovery: Getting this device information Mar 15 16:41:59 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:59 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:59 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3520, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3527, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3534, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3541, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3548, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3555, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3562, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3569, instance 1, run: true Mar 15 16:41:59 volumio2 ntpd[778]: Soliciting pool server 83.215.130.11 Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3576, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3583, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3590, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3597, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3604, instance 1, run: true Mar 15 16:41:59 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 16:41:59 volumio2 volumio[1001]: info: Received Get System Info Mar 15 16:41:59 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 16:41:59 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 16:41:59 volumio2 volumio[1001]: info: Discovery: Getting this device information Mar 15 16:41:59 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:41:59 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:41:59 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3611, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3618, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3625, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3632, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3639, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3646, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3653, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3660, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3667, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3674, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3681, instance 1, run: true Mar 15 16:42:00 volumio2 ntpd[778]: Soliciting pool server 83.137.41.10 Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3688, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3695, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3702, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3709, instance 1, run: true Mar 15 16:42:00 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3716, instance 1, run: true Mar 15 16:42:01 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3723, instance 1, run: true Mar 15 16:42:01 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3730, instance 1, run: true Mar 15 16:42:01 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3737, instance 1, run: true Mar 15 16:42:01 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3744, instance 1, run: true Mar 15 16:42:01 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3751, instance 1, run: true Mar 15 16:42:01 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3758, instance 1, run: true Mar 15 16:42:01 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3765, instance 1, run: true Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 16:42:01 volumio2 volumio[1001]: info: Retrieving Cloud Streaming UI Mar 15 16:42:01 volumio2 volumio[1001]: info: Getting Tidal Cloud Configuration Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 16:42:01 volumio2 volumio[1001]: info: Getting Qobuz Cloud Configuration Mar 15 16:42:01 volumio2 volumio[1001]: info: Asking plugin for UI Config Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 16:42:01 volumio2 volumio[1001]: info: Getting Spotify Cloud Configuration Mar 15 16:42:01 volumio2 volumio[1001]: info: Asking plugin for UI Config Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 16:42:01 volumio2 volumio[1001]: info: Saving Spotify Acccount Mar 15 16:42:01 volumio2 volumio[1001]: info: Got Tidal Cloud Configuration Mar 15 16:42:01 volumio2 volumio[1001]: info: Got it Mar 15 16:42:01 volumio2 volumio[1001]: info: Got it Mar 15 16:42:01 volumio2 volumio[1001]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 15 16:42:01 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3774, instance 1, run: true Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 16:42:01 volumio2 volumio[1001]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 16:42:01 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 16:42:02 volumio2 ntpd[778]: receive: Unexpected origin timestamp 0xeb801e49.af9bbecf does not match aorg 0000000000.00000000 from server@46.102.157.67 xmt 0xeb801e4a.8c17bc90 Mar 15 16:42:02 volumio2 ntpd[778]: receive: Unexpected origin timestamp 0xeb801e49.af8f7c83 does not match aorg 0000000000.00000000 from server@86.59.113.124 xmt 0xeb801e4a.8c46cb67 Mar 15 16:42:02 volumio2 ntpd[778]: receive: Unexpected origin timestamp 0xeb801e49.aed3ee1f does not match aorg 0000000000.00000000 from server@83.137.41.10 xmt 0xeb801e4a.8c90f1e2 Mar 15 16:42:02 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 16:42:02 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3790, instance 1, run: true Mar 15 16:42:02 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3808, instance 1, run: true Mar 15 16:42:02 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3845, instance 1, run: true Mar 15 16:42:02 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3868, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:42:03 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:42:03 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 15 16:42:03 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 15 16:42:03 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 15 16:42:03 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 16:42:03 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3898, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3931, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3957, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 3983, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4015, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4023, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4030, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4037, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4044, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4051, instance 1, run: true Mar 15 16:42:03 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4058, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4065, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4072, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4079, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4086, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4093, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4100, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4107, instance 1, run: true Mar 15 16:42:04 volumio2 go-librespot[1360]: time="2025-03-15T16:42:04+01:00" level=trace msg="sent dealer ping" Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4114, instance 1, run: true Mar 15 16:42:04 volumio2 go-librespot[1360]: time="2025-03-15T16:42:04+01:00" level=trace msg="received dealer pong" Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4121, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4128, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4135, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4142, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4149, instance 1, run: true Mar 15 16:42:04 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4156, instance 1, run: true Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4163, instance 1, run: true Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4170, instance 1, run: true Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4177, instance 1, run: true Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4184, instance 1, run: true Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 15 16:42:05 volumio2 volumio[1001]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 15 16:42:05 volumio2 volumio[1001]: info: Completed starting MyVolumio Plugin Mar 15 16:42:05 volumio2 volumio[1001]: [Metrics] CommandRouter: 52s 187.61ms Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreCommandRouter::volumiosetStartupVolume Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 16:42:05 volumio2 volumio[1001]: info: VolumeController:: Setting startup Volume 60 Mar 15 16:42:05 volumio2 volumio[1001]: info: VolumeController::SetAlsaVolume60 Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreCommandRouter::Close All Modals sent Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreCommandRouter::Close All Modals sent Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreStateMachine::pushState Mar 15 16:42:05 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreCommandRouter::volumioPushState Mar 15 16:42:05 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 15 16:42:05 volumio2 volumio[1001]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 15 16:42:05 volumio2 volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Mar 15 16:42:05 volumio2 volumio[1001]: SPOTIFY: SPOTIFY VOLUME 100 Mar 15 16:42:05 volumio2 volumio[1001]: SPOTIFY: VOLUMIO VOLUME 60 Mar 15 16:42:05 volumio2 volumio[1001]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 15 16:42:05 volumio2 volumio[1001]: info: Setting Spotify Volume from Volumio: 60 Mar 15 16:42:05 volumio2 volumio[1001]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 15 16:42:05 volumio2 volumio[1001]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp stopping service pid 4184... Mar 15 16:42:05 volumio2 sudo[4199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 15 16:42:05 volumio2 sudo[4199]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp service terminated, instance 1 Mar 15 16:42:05 volumio2 volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp service started and running in background, instance 1 Mar 15 16:42:05 volumio2 volumio[1001]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4203, instance 1, run: true Mar 15 16:42:05 volumio2 sudo[4199]: pam_unix(sudo:session): session closed for user root Mar 15 16:42:05 volumio2 volumio[1001]: info: peppymeterbasic Daemon Stop Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4210, instance 1, run: true Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4217, instance 1, run: true Mar 15 16:42:05 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4224, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4231, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4238, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4245, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4252, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4259, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4266, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4273, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4280, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 15 16:42:06 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 15 16:42:06 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4287, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4294, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4301, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4308, instance 1, run: true Mar 15 16:42:06 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4315, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4322, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4329, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4336, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: SPOTIFY: SETTING SPOTIFY VOLUME 60 Mar 15 16:42:07 volumio2 volumio[1001]: info: Sending Spotify command with payload to local API: /player/volume Mar 15 16:42:07 volumio2 go-librespot[1360]: time="2025-03-15T16:42:07+01:00" level=debug msg="update volume to 39321/65535" Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4343, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4364, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4371, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4378, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4385, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4392, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4399, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4406, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4413, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4420, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4427, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4434, instance 1, run: true Mar 15 16:42:07 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4441, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4448, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4455, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4462, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4469, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4476, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4483, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4490, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4497, instance 1, run: true Mar 15 16:42:08 volumio2 go-librespot[1360]: time="2025-03-15T16:42:08+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 15 16:42:08 volumio2 go-librespot[1360]: time="2025-03-15T16:42:08+01:00" level=trace msg="emitting websocket event: volume" Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4504, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: SPOTIFY: received: {"type":"volume","data":{"value":60,"max":100}} Mar 15 16:42:08 volumio2 volumio[1001]: SPOTIFY: RECEIVED SPOTIFY VOLUME 60 Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4511, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4518, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4525, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4532, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4539, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4546, instance 1, run: true Mar 15 16:42:08 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4553, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4560, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4567, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4574, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4581, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4588, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4595, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4602, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4609, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4616, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4623, instance 1, run: true Mar 15 16:42:09 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4630, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4637, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4644, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4651, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4658, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4665, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4672, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4679, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4686, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4693, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4700, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4707, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4714, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4721, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4728, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4735, instance 1, run: true Mar 15 16:42:10 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4742, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4749, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4756, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4763, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4770, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4777, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4784, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4791, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4798, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4805, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4812, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4822, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4829, instance 1, run: true Mar 15 16:42:11 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4836, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4843, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4850, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4857, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4864, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4871, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4878, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4885, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4892, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4899, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: BOOT COMPLETED Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4906, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4913, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4920, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4927, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4934, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4941, instance 1, run: true Mar 15 16:42:12 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 16:42:12 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4948, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4955, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4962, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4969, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4976, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4983, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4990, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 4997, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5004, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5011, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5018, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5025, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5032, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5039, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5046, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5053, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5060, instance 1, run: true Mar 15 16:42:13 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5067, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5074, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5081, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5088, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5095, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5102, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5109, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5116, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5123, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5130, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5137, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5144, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5151, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 16:42:14 volumio2 volumio[1001]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 15 16:42:14 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 15 16:42:14 volumio2 volumio[1001]: info: Received Get System Version Mar 15 16:42:14 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 16:42:14 volumio2 volumio[1001]: info: Received Get System Info Mar 15 16:42:14 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 16:42:14 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 16:42:14 volumio2 volumio[1001]: info: Discovery: Getting this device information Mar 15 16:42:14 volumio2 volumio[1001]: info: CoreCommandRouter::volumioGetState Mar 15 16:42:14 volumio2 volumio[1001]: info: CorePlayQueue::getTrack 0 Mar 15 16:42:14 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5158, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5165, instance 1, run: true Mar 15 16:42:14 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5172, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5179, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5186, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5193, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5200, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5207, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5214, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5221, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5228, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5235, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5242, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5249, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5256, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5263, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5270, instance 1, run: true Mar 15 16:42:15 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5277, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5284, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5291, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5298, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5305, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5312, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5319, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5326, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5333, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5340, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5347, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5354, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5361, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5368, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5375, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5382, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5389, instance 1, run: true Mar 15 16:42:16 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 16:42:16 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5396, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5403, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5410, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5417, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5424, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5443, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5452, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5459, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5466, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5473, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5480, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5487, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5494, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5501, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5508, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5515, instance 1, run: true Mar 15 16:42:17 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5522, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5529, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5536, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5543, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5550, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5557, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5564, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5571, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5578, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5585, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5592, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5599, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5606, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5613, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5620, instance 1, run: true Mar 15 16:42:18 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5627, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5634, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5641, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5648, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5655, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5662, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5669, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5676, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5683, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5690, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5697, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5704, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5711, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5718, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5725, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5732, instance 1, run: true Mar 15 16:42:19 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5739, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5746, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5753, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5760, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5767, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5774, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5781, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5788, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5795, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5802, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5809, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5816, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5823, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5830, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5837, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5844, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5851, instance 1, run: true Mar 15 16:42:20 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 16:42:20 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5858, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5865, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5872, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5879, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5886, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5893, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5900, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5907, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5914, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5921, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5928, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5935, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5942, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5949, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5956, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5963, instance 1, run: true Mar 15 16:42:21 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5970, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5977, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5984, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5991, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 5998, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 6005, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 6012, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 6019, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 6026, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 6033, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 6040, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 6047, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 6054, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 15 16:42:22 volumio2 volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 6062, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: info: camilladsp spawned new process with pid 6077, instance 1, run: true Mar 15 16:42:22 volumio2 volumio[1001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 16:42:22 volumio2 volumio[1001]: Error: dns service error: unknown Mar 15 16:42:22 volumio2 volumio[1001]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Mar 15 16:42:22 volumio2 volumio[1001]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Mar 15 16:42:22 volumio2 volumio[1001]: errorCode: -65537 Mar 15 16:42:22 volumio2 volumio[1001]: } Mar 15 16:42:22 volumio2 volumio[1001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 16:42:23 volumio2 sudo[6093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-15 16:41 Mar 15 16:42:23 volumio2 sudo[6093]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"