Jun 20 00:41:05 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:05 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:05 volumio2 volumio[33895]: info: Discovery: Getting this device information Jun 20 00:41:05 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:05 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:05 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:15 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:15 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:15 volumio2 volumio[33895]: info: Listing playlists Jun 20 00:41:15 volumio2 volumio[33895]: info: Listing playlists Jun 20 00:41:15 volumio2 sudo[36803]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 00:41:15 volumio2 sudo[36805]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 00:41:15 volumio2 sudo[36803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:15 volumio2 sudo[36805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:15 volumio2 sudo[36803]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:15 volumio2 sudo[36805]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:15 volumio2 volumio[33895]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 20 00:41:15 volumio2 sudo[36809]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 00:41:15 volumio2 sudo[36809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:15 volumio2 sudo[36809]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:15 volumio2 sudo[36811]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 00:41:15 volumio2 sudo[36811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:15 volumio2 sudo[36811]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:15 volumio2 volumio[33895]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 20 00:41:15 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 00:41:16 volumio2 volumio[33895]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 20 00:41:16 volumio2 volumio[33895]: info: Received Get System Info Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:16 volumio2 volumio[33895]: info: Discovery: Getting this device information Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:16 volumio2 volumio[33895]: info: Listing playlists Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 20 00:41:16 volumio2 volumio[33895]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 00:41:17 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 20 00:41:17 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 00:41:17 volumio2 volumio[33895]: info: Received Get System Info Jun 20 00:41:17 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:17 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:17 volumio2 volumio[33895]: info: Discovery: Getting this device information Jun 20 00:41:17 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:17 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:18 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 00:41:18 volumio2 volumio[33895]: info: Received Get System Info Jun 20 00:41:18 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:18 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:18 volumio2 volumio[33895]: info: Discovery: Getting this device information Jun 20 00:41:18 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:18 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:18 volumio2 volumio[33895]: info: CoreCommandRouter::volumioGetQueue Jun 20 00:41:18 volumio2 volumio[33895]: info: CoreStateMachine::getQueue Jun 20 00:41:18 volumio2 volumio[33895]: info: CorePlayQueue::getQueue Jun 20 00:41:23 volumio2 volumio[33895]: Searching all installed plugins Jun 20 00:41:23 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:23 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: , search Jun 20 00:41:23 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 20 00:41:23 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 20 00:41:23 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 20 00:41:23 volumio2 volumio[33895]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 20 00:41:23 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 20 00:41:23 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: smart_inputs , search Jun 20 00:41:23 volumio2 volumio[33895]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin smart_inputs Jun 20 00:41:23 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jun 20 00:41:23 volumio2 volumio[33895]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 20 00:41:23 volumio2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 20 00:41:23 volumio2 dbus-daemon[821]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.360' (uid=0 pid=36837 comm="timedatectl show --property=NTPSynchronized --valu") Jun 20 00:41:23 volumio2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 20 00:41:23 volumio2 dbus-daemon[821]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 20 00:41:23 volumio2 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 20 00:41:23 volumio2 setdatetime-helper.sh[36836]: Time is already synchronized. Jun 20 00:41:23 volumio2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 20 00:41:23 volumio2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 20 00:41:23 volumio2 volumio[33895]: info: searchTIDALUri took 223 milliseconds Jun 20 00:41:23 volumio2 volumio[33895]: info: search took 223 milliseconds Jun 20 00:41:24 volumio2 volumio[33895]: Use XMLElement.setAttribute instead Jun 20 00:41:24 volumio2 volumio[33895]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 00:41:24 volumio2 volumio[33895]: TypeError: children[i].attr(...).value is not a function Jun 20 00:41:24 volumio2 volumio[33895]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 20 00:41:24 volumio2 volumio[33895]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 20 00:41:24 volumio2 volumio[33895]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 00:41:24 volumio2 sudo[36854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-20 00:40' Jun 20 00:41:24 volumio2 sudo[36854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:24 volumio2 sudo[36854]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:24 volumio2 vtcs[34418]: [2025-06-20 00:41:24.347] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 20 00:41:24 volumio2 volumio-remote-updater[827]: [2025-06-20 00:41:24] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 20 00:41:24 volumio2 volumio-remote-updater[827]: [2025-06-20 00:41:24] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 20 00:41:24 volumio2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 20 00:41:24 volumio2 go-librespot[34099]: time="2025-06-20T00:41:24+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 20 00:41:24 volumio2 systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 20 00:41:24 volumio2 systemd[1]: volumio.service: Consumed 42.665s CPU time. Jun 20 00:41:24 volumio2 systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 20 00:41:24 volumio2 systemd[1]: dynamicswap.service: Deactivated successfully. Jun 20 00:41:25 volumio2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Jun 20 00:41:25 volumio2 systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 20 00:41:25 volumio2 systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 20 00:41:25 volumio2 systemd[1]: volumio.service: Consumed 42.665s CPU time. Jun 20 00:41:25 volumio2 systemd[1]: Started volumio.service - Volumio Backend Module. Jun 20 00:41:25 volumio2 systemd[1]: dynamicswap.service: Deactivated successfully. Jun 20 00:41:25 volumio2 volumio[36890]: info: ------------------------------------------- Jun 20 00:41:25 volumio2 volumio[36890]: info: ----- Volumio3 ---- Jun 20 00:41:25 volumio2 volumio[36890]: info: ------------------------------------------- Jun 20 00:41:25 volumio2 volumio[36890]: info: ----- System startup ---- Jun 20 00:41:25 volumio2 volumio[36890]: info: ------------------------------------------- Jun 20 00:41:25 volumio2 volumio[36890]: info: MYVOLUMIO Environment detected Jun 20 00:41:25 volumio2 volumio[36890]: info: Plugin folders cleanup Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning into folder /volumio/app/plugins/ Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning category audio_interface Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning category miscellanea Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning category music_service Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning category plugins.json Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning category system_controller Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning category user_interface Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning into folder /data/plugins/ Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning category audio_interface Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning category music_service Jun 20 00:41:25 volumio2 volumio[36890]: info: Scanning category user_interface Jun 20 00:41:25 volumio2 volumio[36890]: info: Plugin folders cleanup completed Jun 20 00:41:25 volumio2 volumio[36890]: info: ------------------------------------------- Jun 20 00:41:25 volumio2 volumio[36890]: info: ----- Core plugins startup ---- Jun 20 00:41:25 volumio2 volumio[36890]: info: ------------------------------------------- Jun 20 00:41:25 volumio2 volumio[36890]: info: Loading plugins from folder /volumio/app/plugins/ Jun 20 00:41:25 volumio2 volumio[36890]: info: Adding plugin upnp to MyMusic Plugins Jun 20 00:41:25 volumio2 volumio[36890]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 20 00:41:25 volumio2 volumio[36890]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 20 00:41:25 volumio2 volumio[36890]: info: Loading plugins from folder /data/plugins/ Jun 20 00:41:25 volumio2 volumio[36890]: info: Loading plugin "system"... Jun 20 00:41:25 volumio2 volumio[36890]: info: Loading plugin "appearance"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "network"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Refreshing Cached IP Addresses Jun 20 00:41:26 volumio2 sudo[36920]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 00:41:26 volumio2 sudo[36920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:26 volumio2 sudo[36922]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 00:41:26 volumio2 sudo[36922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:26 volumio2 sudo[36922]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "services"... Jun 20 00:41:26 volumio2 sudo[36920]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "alsa_controller"... Jun 20 00:41:26 volumio2 sudo[36930]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 20 00:41:26 volumio2 sudo[36930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:26 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "wizard"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "networkfs"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Starting Udev Watcher for removable devices Jun 20 00:41:26 volumio2 volumio[36890]: info: Ignoring mount for partition: boot Jun 20 00:41:26 volumio2 volumio[36890]: info: Ignoring mount for partition: volumio Jun 20 00:41:26 volumio2 volumio[36890]: info: Ignoring mount for partition: volumio_data Jun 20 00:41:26 volumio2 volumio[36890]: info: Ignoring mount for partition: boot Jun 20 00:41:26 volumio2 volumio[36890]: info: Mounting Device SWEEP_FILE Jun 20 00:41:26 volumio2 sudo[36956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/SWEEP_FILE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 20 00:41:26 volumio2 sudo[36956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:26 volumio2 sudo[36956]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:26 volumio2 volumio[36890]: mount: /media/SWEEP_FILE: /dev/sdb2 already mounted on /media/SWEEP_FILE. Jun 20 00:41:26 volumio2 volumio[36890]: dmesg(1) may have more information after failed mount system call. Jun 20 00:41:26 volumio2 volumio[36890]: error: Failed to mount SWEEP_FILE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb2" "/mnt/USB/SWEEP_FILE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 20 00:41:26 volumio2 volumio[36890]: mount: /media/SWEEP_FILE: /dev/sdb2 already mounted on /media/SWEEP_FILE. Jun 20 00:41:26 volumio2 volumio[36890]: dmesg(1) may have more information after failed mount system call. Jun 20 00:41:26 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "volumio_command_line_client"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "upnp"... Jun 20 00:41:26 volumio2 volumio[36890]: info: [1750372886183] Starting Upmpd Daemon Jun 20 00:41:26 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "my_music"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "mpd"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "upnp_browser"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "alarm-clock"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "airplay_emulation"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Starting Shairport Sync Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "last_100"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "webradio"... Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "i2s_dacs"... Jun 20 00:41:26 volumio2 volumio[36890]: info: I2S DAC not set, start Auto-detection Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "volumiodiscovery"... Jun 20 00:41:26 volumio2 volumio[36890]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:26 volumio2 volumio[36890]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:26 volumio2 volumio[36890]: *** WARNING *** For more information see Jun 20 00:41:26 volumio2 volumio[36890]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:26 volumio2 volumio[36890]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:26 volumio2 node[36890]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:26 volumio2 volumio[36890]: *** WARNING *** For more information see Jun 20 00:41:26 volumio2 node[36890]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:26 volumio2 node[36890]: *** WARNING *** For more information see Jun 20 00:41:26 volumio2 node[36890]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:26 volumio2 node[36890]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:26 volumio2 node[36890]: *** WARNING *** For more information see Jun 20 00:41:26 volumio2 volumio[36890]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 20 00:41:26 volumio2 volumio[36890]: info: Discovery: Started advertising with name: Volumio2 Jun 20 00:41:26 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:41:26 volumio2 volumio[36890]: info: Loading plugin "spop"... Jun 20 00:41:27 volumio2 volumio[36890]: info: Loading plugin "now_playing"... Jun 20 00:41:27 volumio2 volumio[36890]: info: Loading plugin "outputs"... Jun 20 00:41:27 volumio2 volumio[36890]: info: Loading plugin "albumart"... Jun 20 00:41:27 volumio2 volumio[36890]: info: Plugin example_plugin is not enabled Jun 20 00:41:27 volumio2 volumio[36890]: info: Loading plugin "inputs"... Jun 20 00:41:27 volumio2 volumio[36890]: info: Loading plugin "updater_comm"... Jun 20 00:41:27 volumio2 volumio[36890]: info: Plugin mpdemulation is not enabled Jun 20 00:41:27 volumio2 volumio[36890]: info: Loading plugin "rest_api"... Jun 20 00:41:27 volumio2 volumio[36890]: info: Loading plugin "websocket"... Jun 20 00:41:27 volumio2 volumio[36890]: info: Starting Socket.io Server version 1.7.4 Jun 20 00:41:27 volumio2 volumio[36890]: info: Loading plugin "fusiondsp"... Jun 20 00:41:27 volumio2 volumio[36962]: Forking 3 albumart workers Jun 20 00:41:27 volumio2 volumio[36890]: info: Applying required configuration parameters for plugin fusiondsp Jun 20 00:41:27 volumio2 volumio[36890]: info: Loading i18n strings for locale de Jun 20 00:41:27 volumio2 volumio[36890]: Updating browse sources language Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::initPlayerControls Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: Express server listening on port 3000 Jun 20 00:41:27 volumio2 volumio[36890]: [Metrics] WebUI: 2s 246.31ms Jun 20 00:41:27 volumio2 volumio[36890]: info: Setting Device type: x86 Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreStateMachine::resetVolumioState Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreStateMachine::getcurrentVolume Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::volumioRetrievevolume Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreStateMachine::pushState Jun 20 00:41:27 volumio2 volumio[36890]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 20 00:41:27 volumio2 volumio[36890]: info: Completed loading Core Plugins Jun 20 00:41:27 volumio2 volumio[36890]: info: Preparing to generate the ALSA configuration file Jun 20 00:41:27 volumio2 volumio[36890]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 00:41:27 volumio2 volumio[36890]: info: Reading ALSA contributions from plugins. Jun 20 00:41:27 volumio2 volumio[36890]: info: Volumio Network Manager: Network status updated: 2 Jun 20 00:41:27 volumio2 volumio[36890]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:27 volumio2 volumio[36890]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:27 volumio2 sudo[36930]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:27 volumio2 volumio[36890]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 20 00:41:27 volumio2 volumio[36972]: Starting albumart workers Jun 20 00:41:27 volumio2 volumio[36890]: info: Reloading queue from file Jun 20 00:41:27 volumio2 volumio[36890]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreStateMachine::setRepeat null single undefined Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreStateMachine::pushState Jun 20 00:41:27 volumio2 volumio[36890]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreStateMachine::setRandom null Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreStateMachine::pushState Jun 20 00:41:27 volumio2 volumio[36890]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:27 volumio2 volumio[36890]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.206 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jun 20 00:41:27 volumio2 volumio[36973]: Starting albumart workers Jun 20 00:41:27 volumio2 volumio[36975]: Starting albumart workers Jun 20 00:41:27 volumio2 volumio[36890]: info: Discovery: adding 04720723-0b84-4186-8f09-f9ac9b9f00f9 Jun 20 00:41:27 volumio2 volumio[36890]: info: Discovery: Found device Volumio-AZ Jun 20 00:41:27 volumio2 volumio[36890]: info: Discovery: Connecting to remote: 192.168.1.11 Jun 20 00:41:27 volumio2 volumio[36890]: info: Discovery: adding 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 20 00:41:27 volumio2 volumio[36890]: info: Discovery: Found device Volumio2 Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:27 volumio2 volumio[36890]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:27 volumio2 volumio[36890]: info: Asound.conf file written Jun 20 00:41:27 volumio2 sudo[37013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 20 00:41:27 volumio2 sudo[37013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:27 volumio2 sudo[37013]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:27 volumio2 volumio[36890]: info: Output device has changed, restarting MPD Jun 20 00:41:27 volumio2 volumio[36890]: info: Output device has changed, restarting Shairport Sync Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:27 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:27 volumio2 sudo[37021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:41:27 volumio2 sudo[37021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:27 volumio2 sudo[37021]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:28 volumio2 sudo[37028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:41:28 volumio2 sudo[37028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 volumio[36890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:28 volumio2 volumio[36890]: info: ___________ START PLUGINS ___________ Jun 20 00:41:28 volumio2 volumio[36890]: info: ControllerMpd::onStart: Initializing MPD Jun 20 00:41:28 volumio2 volumio[36890]: info: Creating MPD Configuration file Jun 20 00:41:28 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:28 volumio2 volumio[36890]: info: [1750372888037] CoreMusicLibrary::Adding element Medienserver Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:28 volumio2 sudo[37035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:41:28 volumio2 sudo[37035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 sudo[37035]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:28 volumio2 sudo[37037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:41:28 volumio2 sudo[37037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:28 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:41:28 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:41:28 volumio2 systemd[1]: mpd.service: Consumed 6.651s CPU time. Jun 20 00:41:28 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:41:28 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:41:28 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:41:28 volumio2 volumio[36890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:28 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:41:28 volumio2 volumio[36890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:28 volumio2 volumio[36890]: info: [1750372888102] CoreMusicLibrary::Adding element Last_100 Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:28 volumio2 volumio[36890]: info: [1750372888103] CoreMusicLibrary::Adding element Webradio Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:28 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:41:28 volumio2 volumio[36890]: info: Initializing BBC Radios Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: Creating Spotify config file Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: [now-playing] ConfigUpdater: config is up to date. Jun 20 00:41:28 volumio2 sudo[37056]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 00:41:28 volumio2 sudo[37056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 00:41:28 volumio2 sudo[37056]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:28 volumio2 volumio[36890]: info: Loading i18n strings for locale de Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: FusionDsp - mixtype--------------------- None Jun 20 00:41:28 volumio2 volumio[36890]: info: Volumio Calling Home Jun 20 00:41:28 volumio2 volumio[36890]: info: Preparing to generate the ALSA configuration file Jun 20 00:41:28 volumio2 volumio[36890]: info: [now-playing] App is listening on port 4004. Jun 20 00:41:28 volumio2 volumio[36890]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 20 00:41:28 volumio2 volumio[36890]: info: Discovery: this is already registered, 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 20 00:41:28 volumio2 volumio[36890]: info: Discovery: Found device Volumio2 Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:28 volumio2 volumio[36890]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:28 volumio2 volumio[36890]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 00:41:28 volumio2 volumio[36890]: info: Reading ALSA contributions from plugins. Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 20 00:41:28 volumio2 volumio[36890]: info: Discovery: Connected to remote: 192.168.1.11 Jun 20 00:41:28 volumio2 volumio[36890]: info: MPD Permissions set Jun 20 00:41:28 volumio2 volumio[36890]: info: MPD Permissions set Jun 20 00:41:28 volumio2 volumio[36890]: info: Volumio called home Jun 20 00:41:28 volumio2 volumio[36890]: info: Spotify config file written Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 sudo[37081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 20 00:41:28 volumio2 sudo[37081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:41:28 volumio2 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jun 20 00:41:28 volumio2 systemd[1]: go-librespot-daemon.service: Killing process 34112 (go-librespot) with signal SIGKILL. Jun 20 00:41:28 volumio2 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jun 20 00:41:28 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:41:28 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 20 00:41:28 volumio2 go-librespot[37092]: go-librespot daemon starting... Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: No need to fix Spotify hosts Jun 20 00:41:28 volumio2 sudo[37081]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:28 volumio2 volumio[36890]: info: Discovery: Getting this device information Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:28 volumio2 volumio[36890]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:28 volumio2 go-librespot[37101]: time="2025-06-20T00:41:28+02:00" level=info msg="running go-librespot 0.2.0" Jun 20 00:41:28 volumio2 go-librespot[37101]: time="2025-06-20T00:41:28+02:00" level=debug msg="app state loaded" Jun 20 00:41:28 volumio2 go-librespot[37101]: time="2025-06-20T00:41:28+02:00" level=debug msg="stored credentials not found" Jun 20 00:41:28 volumio2 go-librespot[37101]: time="2025-06-20T00:41:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:28 volumio2 volumio[36890]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:28 volumio2 volumio[36890]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 20 00:41:28 volumio2 volumio[36890]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 20 00:41:28 volumio2 volumio[36890]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 20 00:41:28 volumio2 volumio[36890]: info: Starting Shairport Sync Jun 20 00:41:28 volumio2 go-librespot[37101]: time="2025-06-20T00:41:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 20 00:41:28 volumio2 go-librespot[37101]: time="2025-06-20T00:41:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 20 00:41:28 volumio2 go-librespot[37101]: time="2025-06-20T00:41:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 20 00:41:28 volumio2 go-librespot[37101]: time="2025-06-20T00:41:28+02:00" level=info msg="zeroconf server listening on port 37983" Jun 20 00:41:28 volumio2 volumio[36890]: info: Starting Shairport Sync Jun 20 00:41:28 volumio2 volumio[36890]: info: Starting Shairport Sync Jun 20 00:41:28 volumio2 sudo[37110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:28 volumio2 sudo[37110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 sudo[37112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:28 volumio2 sudo[37112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 volumio[36890]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.206 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jun 20 00:41:28 volumio2 sudo[37115]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:28 volumio2 sudo[37115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 volumio[36890]: info: Asound.conf file unchanged, so no further update is needed Jun 20 00:41:28 volumio2 volumio[36890]: info: Output device has changed, restarting MPD Jun 20 00:41:28 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 20 00:41:28 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 20 00:41:28 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:28 volumio2 systemd[1]: shairport-sync.service: Consumed 10.489s CPU time. Jun 20 00:41:28 volumio2 volumio[36890]: info: Output device has changed, restarting Shairport Sync Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:28 volumio2 sudo[37122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:41:28 volumio2 sudo[37120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:41:28 volumio2 sudo[37122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 sudo[37120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 sudo[37122]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:28 volumio2 volumio[36890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:28 volumio2 volumio[36890]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 20 00:41:28 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:28 volumio2 sudo[37115]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:28 volumio2 sudo[37112]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:28 volumio2 volumio[36890]: info: MPD Permissions set Jun 20 00:41:28 volumio2 volumio[36890]: info: Shairport-Sync Started Jun 20 00:41:28 volumio2 volumio[36890]: Error adding Membership: Error: addMembership EINVAL Jun 20 00:41:28 volumio2 sudo[37110]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:28 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:41:28 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:41:28 volumio2 volumio[36890]: info: Shairport-Sync Started Jun 20 00:41:28 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:41:28 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:41:28 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:41:28 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:41:28 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:41:28 volumio2 volumio[36890]: info: Shairport-Sync Started Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:28 volumio2 volumio[36890]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:28 volumio2 volumio[36890]: info: Starting Shairport Sync Jun 20 00:41:28 volumio2 sudo[37130]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 00:41:28 volumio2 sudo[37130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 00:41:28 volumio2 sudo[37130]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:28 volumio2 volumio[36890]: Searching all installed plugins Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: , search Jun 20 00:41:28 volumio2 volumio[36890]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 20 00:41:28 volumio2 volumio[36890]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 00:41:28 volumio2 sudo[37154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:28 volumio2 sudo[37154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 volumio[36890]: TypeError: Cannot read properties of undefined (reading 'then') Jun 20 00:41:28 volumio2 volumio[36890]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1583:17) Jun 20 00:41:28 volumio2 volumio[36890]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 20 00:41:28 volumio2 volumio[36890]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Jun 20 00:41:28 volumio2 volumio[36890]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Jun 20 00:41:28 volumio2 volumio[36890]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Jun 20 00:41:28 volumio2 volumio[36890]: at Socket.emit (node:events:514:28) Jun 20 00:41:28 volumio2 volumio[36890]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 20 00:41:28 volumio2 volumio[36890]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jun 20 00:41:28 volumio2 volumio[36890]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 00:41:28 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 20 00:41:28 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 20 00:41:28 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:28 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:28 volumio2 sudo[37154]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:28 volumio2 sudo[37187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-20 00:40' Jun 20 00:41:28 volumio2 sudo[37187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:28 volumio2 sudo[37187]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:29 volumio2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 20 00:41:29 volumio2 kernel: upmpdcli[34247]: segfault at 18 ip 00007fc0dd4d2640 sp 00007fc0d703e8e8 error 4 in libmpdclient.so.2.20[b640,7fc0dd4cf000+e000] likely on CPU 0 (core 0, socket 0) Jun 20 00:41:29 volumio2 kernel: Code: 8b 47 24 c3 50 48 8d 0d 9e b5 00 00 ba 41 00 00 00 48 8d 35 05 b5 00 00 48 8d 3d 43 b5 00 00 e8 f6 cd ff ff 66 0f 1f 44 00 00 <8b> 57 18 53 31 c0 48 89 fb 83 fa 09 77 22 48 c7 c1 f2 fd ff ff 48 Jun 20 00:41:29 volumio2 sudo[37120]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:29 volumio2 sudo[37037]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:29 volumio2 systemd[1]: upmpdcli.service: Main process exited, code=killed, status=11/SEGV Jun 20 00:41:29 volumio2 sudo[37028]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:29 volumio2 systemd[1]: upmpdcli.service: Failed with result 'signal'. Jun 20 00:41:29 volumio2 systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 20 00:41:29 volumio2 systemd[1]: volumio.service: Consumed 6.560s CPU time. Jun 20 00:41:29 volumio2 systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 20 00:41:29 volumio2 systemd[1]: dynamicswap.service: Deactivated successfully. Jun 20 00:41:29 volumio2 mpd[37156]: 2025-06-20T00:41:29 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 20 00:41:29 volumio2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Jun 20 00:41:29 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Jun 20 00:41:29 volumio2 systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 20 00:41:29 volumio2 systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 20 00:41:29 volumio2 systemd[1]: volumio.service: Consumed 6.560s CPU time. Jun 20 00:41:29 volumio2 systemd[1]: Started volumio.service - Volumio Backend Module. Jun 20 00:41:29 volumio2 systemd[1]: dynamicswap.service: Deactivated successfully. Jun 20 00:41:29 volumio2 volumio-remote-updater[827]: [2025-06-20 00:41:29] [info] asio async_connect error: asio.system:111 (Connection refused) Jun 20 00:41:29 volumio2 volumio-remote-updater[827]: [2025-06-20 00:41:29] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jun 20 00:41:29 volumio2 volumio-remote-updater[827]: [2025-06-20 00:41:29] [error] handle_connect error: Connection refused Jun 20 00:41:29 volumio2 volumio[37204]: info: ------------------------------------------- Jun 20 00:41:29 volumio2 volumio[37204]: info: ----- Volumio3 ---- Jun 20 00:41:29 volumio2 volumio[37204]: info: ------------------------------------------- Jun 20 00:41:29 volumio2 volumio[37204]: info: ----- System startup ---- Jun 20 00:41:29 volumio2 volumio[37204]: info: ------------------------------------------- Jun 20 00:41:29 volumio2 volumio[37204]: info: MYVOLUMIO Environment detected Jun 20 00:41:30 volumio2 volumio[37204]: info: Plugin folders cleanup Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning into folder /volumio/app/plugins/ Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning category audio_interface Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning category miscellanea Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning category music_service Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning category plugins.json Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning category system_controller Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning category user_interface Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning into folder /data/plugins/ Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning category audio_interface Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning category music_service Jun 20 00:41:30 volumio2 volumio[37204]: info: Scanning category user_interface Jun 20 00:41:30 volumio2 volumio[37204]: info: Plugin folders cleanup completed Jun 20 00:41:30 volumio2 volumio[37204]: info: ------------------------------------------- Jun 20 00:41:30 volumio2 volumio[37204]: info: ----- Core plugins startup ---- Jun 20 00:41:30 volumio2 volumio[37204]: info: ------------------------------------------- Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugins from folder /volumio/app/plugins/ Jun 20 00:41:30 volumio2 volumio[37204]: info: Adding plugin upnp to MyMusic Plugins Jun 20 00:41:30 volumio2 volumio[37204]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 20 00:41:30 volumio2 volumio[37204]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugins from folder /data/plugins/ Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "system"... Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "appearance"... Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "network"... Jun 20 00:41:30 volumio2 volumio[37204]: info: Refreshing Cached IP Addresses Jun 20 00:41:30 volumio2 sudo[37233]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 00:41:30 volumio2 sudo[37235]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 00:41:30 volumio2 sudo[37233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "services"... Jun 20 00:41:30 volumio2 sudo[37235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:30 volumio2 sudo[37235]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "alsa_controller"... Jun 20 00:41:30 volumio2 sudo[37233]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:30 volumio2 sudo[37248]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 20 00:41:30 volumio2 sudo[37248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:30 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "wizard"... Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "networkfs"... Jun 20 00:41:30 volumio2 volumio[37204]: info: Starting Udev Watcher for removable devices Jun 20 00:41:30 volumio2 volumio[37204]: info: Ignoring mount for partition: boot Jun 20 00:41:30 volumio2 volumio[37204]: info: Ignoring mount for partition: volumio Jun 20 00:41:30 volumio2 volumio[37204]: info: Ignoring mount for partition: volumio_data Jun 20 00:41:30 volumio2 volumio[37204]: info: Ignoring mount for partition: boot Jun 20 00:41:30 volumio2 volumio[37204]: info: Mounting Device SWEEP_FILE Jun 20 00:41:30 volumio2 sudo[37270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/SWEEP_FILE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 20 00:41:30 volumio2 sudo[37270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:30 volumio2 sudo[37270]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:30 volumio2 volumio[37204]: mount: /media/SWEEP_FILE: /dev/sdb2 already mounted on /media/SWEEP_FILE. Jun 20 00:41:30 volumio2 volumio[37204]: dmesg(1) may have more information after failed mount system call. Jun 20 00:41:30 volumio2 volumio[37204]: error: Failed to mount SWEEP_FILE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb2" "/mnt/USB/SWEEP_FILE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 20 00:41:30 volumio2 volumio[37204]: mount: /media/SWEEP_FILE: /dev/sdb2 already mounted on /media/SWEEP_FILE. Jun 20 00:41:30 volumio2 volumio[37204]: dmesg(1) may have more information after failed mount system call. Jun 20 00:41:30 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "volumio_command_line_client"... Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "upnp"... Jun 20 00:41:30 volumio2 volumio[37204]: info: [1750372890420] Starting Upmpd Daemon Jun 20 00:41:30 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "my_music"... Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "mpd"... Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "upnp_browser"... Jun 20 00:41:30 volumio2 volumio[37204]: info: Loading plugin "alarm-clock"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "airplay_emulation"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Starting Shairport Sync Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "last_100"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "webradio"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "i2s_dacs"... Jun 20 00:41:31 volumio2 volumio[37204]: info: I2S DAC not set, start Auto-detection Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "volumiodiscovery"... Jun 20 00:41:31 volumio2 volumio[37204]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:31 volumio2 volumio[37204]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:31 volumio2 volumio[37204]: *** WARNING *** For more information see Jun 20 00:41:31 volumio2 volumio[37204]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:31 volumio2 volumio[37204]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:31 volumio2 node[37204]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:31 volumio2 volumio[37204]: *** WARNING *** For more information see Jun 20 00:41:31 volumio2 node[37204]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:31 volumio2 node[37204]: *** WARNING *** For more information see Jun 20 00:41:31 volumio2 node[37204]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:31 volumio2 node[37204]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:31 volumio2 node[37204]: *** WARNING *** For more information see Jun 20 00:41:31 volumio2 volumio[37204]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 20 00:41:31 volumio2 volumio[37204]: info: Discovery: Started advertising with name: Volumio2 Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "spop"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "now_playing"... Jun 20 00:41:31 volumio2 sudo[37248]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "outputs"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "albumart"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Plugin example_plugin is not enabled Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "inputs"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "updater_comm"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Plugin mpdemulation is not enabled Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "rest_api"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "websocket"... Jun 20 00:41:31 volumio2 volumio[37204]: info: Starting Socket.io Server version 1.7.4 Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading plugin "fusiondsp"... Jun 20 00:41:31 volumio2 volumio[37275]: Forking 3 albumart workers Jun 20 00:41:31 volumio2 volumio[37204]: info: Applying required configuration parameters for plugin fusiondsp Jun 20 00:41:31 volumio2 volumio[37204]: info: Loading i18n strings for locale de Jun 20 00:41:31 volumio2 volumio[37204]: Updating browse sources language Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::initPlayerControls Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: Express server listening on port 3000 Jun 20 00:41:31 volumio2 volumio[37204]: [Metrics] WebUI: 2s 293.76ms Jun 20 00:41:31 volumio2 volumio[37204]: info: Setting Device type: x86 Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreStateMachine::resetVolumioState Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreStateMachine::getcurrentVolume Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::volumioRetrievevolume Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreStateMachine::pushState Jun 20 00:41:31 volumio2 volumio[37204]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:31 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:31 volumio2 volumio[37204]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 20 00:41:31 volumio2 volumio[37204]: info: Completed loading Core Plugins Jun 20 00:41:31 volumio2 volumio[37204]: info: Preparing to generate the ALSA configuration file Jun 20 00:41:31 volumio2 volumio[37285]: Starting albumart workers Jun 20 00:41:31 volumio2 volumio[37204]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 00:41:31 volumio2 volumio[37204]: info: Reading ALSA contributions from plugins. Jun 20 00:41:32 volumio2 volumio[37204]: info: Volumio Network Manager: Network status updated: 2 Jun 20 00:41:32 volumio2 volumio[37287]: Starting albumart workers Jun 20 00:41:32 volumio2 volumio[37204]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 20 00:41:32 volumio2 volumio[37286]: Starting albumart workers Jun 20 00:41:32 volumio2 volumio[37204]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 20 00:41:32 volumio2 volumio[37204]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.206 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jun 20 00:41:32 volumio2 volumio[37204]: info: Reloading queue from file Jun 20 00:41:32 volumio2 volumio[37204]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreStateMachine::setRepeat null single undefined Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreStateMachine::pushState Jun 20 00:41:32 volumio2 volumio[37204]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreStateMachine::setRandom null Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreStateMachine::pushState Jun 20 00:41:32 volumio2 volumio[37204]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:32 volumio2 volumio[37204]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 20 00:41:32 volumio2 volumio[37204]: info: Discovery: adding 04720723-0b84-4186-8f09-f9ac9b9f00f9 Jun 20 00:41:32 volumio2 volumio[37204]: info: Discovery: Found device Volumio-AZ Jun 20 00:41:32 volumio2 volumio[37204]: info: Discovery: Connecting to remote: 192.168.1.11 Jun 20 00:41:32 volumio2 volumio[37204]: info: Asound.conf file unchanged, so no further update is needed Jun 20 00:41:32 volumio2 volumio[37204]: info: Output device has changed, restarting MPD Jun 20 00:41:32 volumio2 sudo[37330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:41:32 volumio2 volumio[37204]: info: Output device has changed, restarting Shairport Sync Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:32 volumio2 sudo[37330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 sudo[37330]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:32 volumio2 sudo[37332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:41:32 volumio2 sudo[37332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 volumio[37204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:32 volumio2 volumio[37204]: info: ___________ START PLUGINS ___________ Jun 20 00:41:32 volumio2 volumio[37204]: info: ControllerMpd::onStart: Initializing MPD Jun 20 00:41:32 volumio2 volumio[37204]: info: Creating MPD Configuration file Jun 20 00:41:32 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:32 volumio2 volumio[37204]: info: [1750372892252] CoreMusicLibrary::Adding element Medienserver Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:32 volumio2 sudo[37349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:41:32 volumio2 sudo[37354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:41:32 volumio2 sudo[37349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 sudo[37354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:32 volumio2 sudo[37349]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:32 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:41:32 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:41:32 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:41:32 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:41:32 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:41:32 volumio2 volumio[37204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:32 volumio2 volumio[37204]: info: [1750372892299] CoreMusicLibrary::Adding element Last_100 Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:32 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:32 volumio2 volumio[37204]: info: [1750372892300] CoreMusicLibrary::Adding element Webradio Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:32 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:41:32 volumio2 volumio[37204]: info: Initializing BBC Radios Jun 20 00:41:32 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:41:32 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:41:32 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:41:32 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:41:32 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:41:32 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:41:32 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: Creating Spotify config file Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: [now-playing] ConfigUpdater: config is up to date. Jun 20 00:41:32 volumio2 volumio[37204]: info: Loading i18n strings for locale de Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: FusionDsp - mixtype--------------------- None Jun 20 00:41:32 volumio2 volumio[37204]: info: Volumio Calling Home Jun 20 00:41:32 volumio2 sudo[37372]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 00:41:32 volumio2 sudo[37372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 00:41:32 volumio2 volumio[37204]: info: Preparing to generate the ALSA configuration file Jun 20 00:41:32 volumio2 sudo[37372]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:32 volumio2 volumio[37204]: info: [now-playing] App is listening on port 4004. Jun 20 00:41:32 volumio2 volumio[37204]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 20 00:41:32 volumio2 volumio[37204]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 00:41:32 volumio2 volumio[37204]: info: Reading ALSA contributions from plugins. Jun 20 00:41:32 volumio2 volumio[37204]: info: Discovery: adding 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 20 00:41:32 volumio2 volumio[37204]: info: Discovery: Found device Volumio2 Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:32 volumio2 volumio[37204]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:32 volumio2 volumio[37204]: info: MPD Permissions set Jun 20 00:41:32 volumio2 volumio[37204]: info: MPD Permissions set Jun 20 00:41:32 volumio2 volumio[37204]: info: Discovery: Connected to remote: 192.168.1.11 Jun 20 00:41:32 volumio2 volumio[37204]: info: Spotify config file written Jun 20 00:41:32 volumio2 volumio[37204]: info: Discovery: this is already registered, 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 20 00:41:32 volumio2 volumio[37204]: info: Discovery: Found device Volumio2 Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:32 volumio2 volumio[37204]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:32 volumio2 sudo[37389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 20 00:41:32 volumio2 sudo[37389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 volumio[37204]: info: Volumio called home Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jun 20 00:41:32 volumio2 systemd[1]: go-librespot-daemon.service: Killing process 37107 (go-librespot) with signal SIGKILL. Jun 20 00:41:32 volumio2 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jun 20 00:41:32 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:41:32 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 20 00:41:32 volumio2 go-librespot[37397]: go-librespot daemon starting... Jun 20 00:41:32 volumio2 sudo[37389]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 go-librespot[37407]: time="2025-06-20T00:41:32+02:00" level=info msg="running go-librespot 0.2.0" Jun 20 00:41:32 volumio2 go-librespot[37407]: time="2025-06-20T00:41:32+02:00" level=debug msg="app state loaded" Jun 20 00:41:32 volumio2 go-librespot[37407]: time="2025-06-20T00:41:32+02:00" level=debug msg="stored credentials not found" Jun 20 00:41:32 volumio2 go-librespot[37407]: time="2025-06-20T00:41:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 20 00:41:32 volumio2 volumio[37204]: info: No need to fix Spotify hosts Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 20 00:41:32 volumio2 volumio[37204]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 20 00:41:32 volumio2 volumio[37204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 20 00:41:32 volumio2 go-librespot[37407]: time="2025-06-20T00:41:32+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 20 00:41:32 volumio2 go-librespot[37407]: time="2025-06-20T00:41:32+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 20 00:41:32 volumio2 go-librespot[37407]: time="2025-06-20T00:41:32+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 20 00:41:32 volumio2 go-librespot[37407]: time="2025-06-20T00:41:32+02:00" level=info msg="zeroconf server listening on port 46197" Jun 20 00:41:32 volumio2 volumio[37204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 20 00:41:32 volumio2 volumio[37204]: info: Starting Shairport Sync Jun 20 00:41:32 volumio2 volumio[37204]: info: Starting Shairport Sync Jun 20 00:41:32 volumio2 volumio[37204]: info: Starting Shairport Sync Jun 20 00:41:32 volumio2 sudo[37416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:32 volumio2 sudo[37418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:32 volumio2 sudo[37418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 sudo[37420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:32 volumio2 sudo[37416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 sudo[37420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:32 volumio2 volumio[37204]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:32 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 20 00:41:32 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:32 volumio2 systemd[1]: shairport-sync.service: Consumed 1.534s CPU time. Jun 20 00:41:32 volumio2 volumio[37204]: info: Asound.conf file unchanged, so no further update is needed Jun 20 00:41:32 volumio2 volumio[37204]: info: Output device has changed, restarting MPD Jun 20 00:41:32 volumio2 volumio[37204]: info: Output device has changed, restarting Shairport Sync Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:32 volumio2 sudo[37426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:41:32 volumio2 sudo[37426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 sudo[37428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:41:32 volumio2 sudo[37428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:32 volumio2 sudo[37418]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:32 volumio2 sudo[37426]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:32 volumio2 volumio[37204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:32 volumio2 volumio[37204]: Searching all installed plugins Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: , search Jun 20 00:41:32 volumio2 volumio[37204]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 20 00:41:32 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 20 00:41:32 volumio2 volumio[37204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 00:41:32 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 20 00:41:32 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:32 volumio2 volumio[37204]: TypeError: Cannot read properties of undefined (reading 'then') Jun 20 00:41:32 volumio2 volumio[37204]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1583:17) Jun 20 00:41:32 volumio2 volumio[37204]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 20 00:41:32 volumio2 volumio[37204]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Jun 20 00:41:32 volumio2 volumio[37204]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Jun 20 00:41:32 volumio2 volumio[37204]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Jun 20 00:41:32 volumio2 volumio[37204]: at Socket.emit (node:events:514:28) Jun 20 00:41:32 volumio2 volumio[37204]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 20 00:41:32 volumio2 volumio[37204]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jun 20 00:41:32 volumio2 volumio[37204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 00:41:32 volumio2 sudo[37420]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:32 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:32 volumio2 sudo[37416]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:32 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:41:32 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:41:32 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:41:32 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:41:32 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:41:32 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:41:32 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:41:32 volumio2 sudo[37449]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 00:41:32 volumio2 sudo[37449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 00:41:32 volumio2 sudo[37449]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:32 volumio2 sudo[37469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-20 00:40' Jun 20 00:41:32 volumio2 sudo[37469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:32 volumio2 sudo[37469]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:33 volumio2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 20 00:41:33 volumio2 sudo[37354]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:33 volumio2 sudo[37428]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:33 volumio2 sudo[37332]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:33 volumio2 systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 20 00:41:33 volumio2 systemd[1]: volumio.service: Consumed 6.288s CPU time. Jun 20 00:41:33 volumio2 systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 20 00:41:33 volumio2 systemd[1]: dynamicswap.service: Deactivated successfully. Jun 20 00:41:33 volumio2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Jun 20 00:41:33 volumio2 systemd[1]: dynamicswap.service: Start request repeated too quickly. Jun 20 00:41:33 volumio2 systemd[1]: dynamicswap.service: Failed with result 'start-limit-hit'. Jun 20 00:41:33 volumio2 systemd[1]: Failed to start dynamicswap.service - dynamicswap service. Jun 20 00:41:33 volumio2 systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 20 00:41:33 volumio2 systemd[1]: volumio.service: Consumed 6.288s CPU time. Jun 20 00:41:33 volumio2 systemd[1]: Started volumio.service - Volumio Backend Module. Jun 20 00:41:33 volumio2 mpd[37466]: 2025-06-20T00:41:33 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 20 00:41:33 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Jun 20 00:41:33 volumio2 volumio[37480]: info: ------------------------------------------- Jun 20 00:41:33 volumio2 volumio[37480]: info: ----- Volumio3 ---- Jun 20 00:41:33 volumio2 volumio[37480]: info: ------------------------------------------- Jun 20 00:41:33 volumio2 volumio[37480]: info: ----- System startup ---- Jun 20 00:41:33 volumio2 volumio[37480]: info: ------------------------------------------- Jun 20 00:41:34 volumio2 volumio[37480]: info: MYVOLUMIO Environment detected Jun 20 00:41:34 volumio2 volumio[37480]: info: Plugin folders cleanup Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning into folder /volumio/app/plugins/ Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning category audio_interface Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning category miscellanea Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning category music_service Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning category plugins.json Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning category system_controller Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning category user_interface Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning into folder /data/plugins/ Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning category audio_interface Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning category music_service Jun 20 00:41:34 volumio2 volumio[37480]: info: Scanning category user_interface Jun 20 00:41:34 volumio2 volumio[37480]: info: Plugin folders cleanup completed Jun 20 00:41:34 volumio2 volumio[37480]: info: ------------------------------------------- Jun 20 00:41:34 volumio2 volumio[37480]: info: ----- Core plugins startup ---- Jun 20 00:41:34 volumio2 volumio[37480]: info: ------------------------------------------- Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugins from folder /volumio/app/plugins/ Jun 20 00:41:34 volumio2 volumio[37480]: info: Adding plugin upnp to MyMusic Plugins Jun 20 00:41:34 volumio2 volumio[37480]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 20 00:41:34 volumio2 volumio[37480]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugins from folder /data/plugins/ Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "system"... Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "appearance"... Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "network"... Jun 20 00:41:34 volumio2 volumio[37480]: info: Refreshing Cached IP Addresses Jun 20 00:41:34 volumio2 sudo[37508]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 00:41:34 volumio2 sudo[37506]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 00:41:34 volumio2 sudo[37506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "services"... Jun 20 00:41:34 volumio2 sudo[37508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:34 volumio2 sudo[37506]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:34 volumio2 sudo[37508]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "alsa_controller"... Jun 20 00:41:34 volumio2 sudo[37516]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 20 00:41:34 volumio2 sudo[37516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:34 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "wizard"... Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "networkfs"... Jun 20 00:41:34 volumio2 volumio[37480]: info: Starting Udev Watcher for removable devices Jun 20 00:41:34 volumio2 volumio[37480]: info: Ignoring mount for partition: boot Jun 20 00:41:34 volumio2 volumio[37480]: info: Ignoring mount for partition: volumio Jun 20 00:41:34 volumio2 volumio[37480]: info: Ignoring mount for partition: volumio_data Jun 20 00:41:34 volumio2 volumio[37480]: info: Ignoring mount for partition: boot Jun 20 00:41:34 volumio2 volumio[37480]: info: Mounting Device SWEEP_FILE Jun 20 00:41:34 volumio2 sudo[37543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/SWEEP_FILE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 20 00:41:34 volumio2 sudo[37543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:34 volumio2 sudo[37543]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:34 volumio2 volumio[37480]: mount: /media/SWEEP_FILE: /dev/sdb2 already mounted on /media/SWEEP_FILE. Jun 20 00:41:34 volumio2 volumio[37480]: dmesg(1) may have more information after failed mount system call. Jun 20 00:41:34 volumio2 volumio[37480]: error: Failed to mount SWEEP_FILE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb2" "/mnt/USB/SWEEP_FILE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 20 00:41:34 volumio2 volumio[37480]: mount: /media/SWEEP_FILE: /dev/sdb2 already mounted on /media/SWEEP_FILE. Jun 20 00:41:34 volumio2 volumio[37480]: dmesg(1) may have more information after failed mount system call. Jun 20 00:41:34 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "volumio_command_line_client"... Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "upnp"... Jun 20 00:41:34 volumio2 volumio[37480]: info: [1750372894457] Starting Upmpd Daemon Jun 20 00:41:34 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "my_music"... Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "mpd"... Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "upnp_browser"... Jun 20 00:41:34 volumio2 volumio[37480]: info: Loading plugin "alarm-clock"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "airplay_emulation"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Starting Shairport Sync Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "last_100"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "webradio"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "i2s_dacs"... Jun 20 00:41:35 volumio2 volumio[37480]: info: I2S DAC not set, start Auto-detection Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "volumiodiscovery"... Jun 20 00:41:35 volumio2 volumio[37480]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:35 volumio2 volumio[37480]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:35 volumio2 volumio[37480]: *** WARNING *** For more information see Jun 20 00:41:35 volumio2 volumio[37480]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:35 volumio2 volumio[37480]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:35 volumio2 node[37480]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:35 volumio2 volumio[37480]: *** WARNING *** For more information see Jun 20 00:41:35 volumio2 node[37480]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:35 volumio2 node[37480]: *** WARNING *** For more information see Jun 20 00:41:35 volumio2 node[37480]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 20 00:41:35 volumio2 node[37480]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:41:35 volumio2 node[37480]: *** WARNING *** For more information see Jun 20 00:41:35 volumio2 volumio[37480]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 20 00:41:35 volumio2 volumio[37480]: info: Discovery: Started advertising with name: Volumio2 Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "spop"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "now_playing"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "outputs"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "albumart"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Plugin example_plugin is not enabled Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "inputs"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "updater_comm"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Plugin mpdemulation is not enabled Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "rest_api"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "websocket"... Jun 20 00:41:35 volumio2 volumio[37480]: info: Starting Socket.io Server version 1.7.4 Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading plugin "fusiondsp"... Jun 20 00:41:35 volumio2 volumio[37548]: Forking 3 albumart workers Jun 20 00:41:35 volumio2 volumio[37480]: info: Applying required configuration parameters for plugin fusiondsp Jun 20 00:41:35 volumio2 volumio[37480]: info: Loading i18n strings for locale de Jun 20 00:41:35 volumio2 volumio[37480]: Updating browse sources language Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::initPlayerControls Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: Express server listening on port 3000 Jun 20 00:41:35 volumio2 volumio[37480]: [Metrics] WebUI: 2s 281.54ms Jun 20 00:41:35 volumio2 volumio[37480]: info: Setting Device type: x86 Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreStateMachine::resetVolumioState Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreStateMachine::getcurrentVolume Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::volumioRetrievevolume Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreStateMachine::pushState Jun 20 00:41:35 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:35 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:35 volumio2 volumio[37480]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 20 00:41:35 volumio2 volumio[37480]: info: Completed loading Core Plugins Jun 20 00:41:36 volumio2 volumio[37480]: info: Preparing to generate the ALSA configuration file Jun 20 00:41:36 volumio2 volumio[37480]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 00:41:36 volumio2 volumio[37480]: info: Reading ALSA contributions from plugins. Jun 20 00:41:36 volumio2 volumio[37480]: info: Volumio Network Manager: Network status updated: 2 Jun 20 00:41:36 volumio2 volumio[37480]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 20 00:41:36 volumio2 volumio[37560]: Starting albumart workers Jun 20 00:41:36 volumio2 volumio[37559]: Starting albumart workers Jun 20 00:41:36 volumio2 volumio[37558]: Starting albumart workers Jun 20 00:41:36 volumio2 volumio[37480]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 20 00:41:36 volumio2 volumio[37480]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 20 00:41:36 volumio2 volumio[37480]: info: Reloading queue from file Jun 20 00:41:36 volumio2 volumio[37480]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.206 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreStateMachine::setRepeat null single undefined Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreStateMachine::pushState Jun 20 00:41:36 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreStateMachine::setRandom null Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreStateMachine::pushState Jun 20 00:41:36 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:36 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:36 volumio2 volumio[37480]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 20 00:41:36 volumio2 volumio[37480]: info: Discovery: adding 04720723-0b84-4186-8f09-f9ac9b9f00f9 Jun 20 00:41:36 volumio2 volumio[37480]: info: Discovery: Found device Volumio-AZ Jun 20 00:41:36 volumio2 volumio[37480]: info: Discovery: Connecting to remote: 192.168.1.11 Jun 20 00:41:36 volumio2 volumio[37480]: info: Asound.conf file unchanged, so no further update is needed Jun 20 00:41:36 volumio2 volumio[37480]: info: Output device has changed, restarting MPD Jun 20 00:41:36 volumio2 volumio[37480]: info: Output device has changed, restarting Shairport Sync Jun 20 00:41:36 volumio2 sudo[37596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:36 volumio2 sudo[37596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 sudo[37596]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:36 volumio2 sudo[37598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:41:36 volumio2 sudo[37598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 20 00:41:36 volumio2 volumio[37480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:36 volumio2 volumio[37480]: info: ___________ START PLUGINS ___________ Jun 20 00:41:36 volumio2 volumio[37480]: info: ControllerMpd::onStart: Initializing MPD Jun 20 00:41:36 volumio2 volumio[37480]: info: Creating MPD Configuration file Jun 20 00:41:36 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:41:36 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:41:36 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:41:36 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:41:36 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:36 volumio2 volumio[37480]: info: [1750372896308] CoreMusicLibrary::Adding element Medienserver Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:36 volumio2 sudo[37626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:41:36 volumio2 sudo[37626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 sudo[37624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:41:36 volumio2 sudo[37624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:36 volumio2 sudo[37624]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:36 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:41:36 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:41:36 volumio2 volumio[37480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:36 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:41:36 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:41:36 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:41:36 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:41:36 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:41:36 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:41:36 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:41:36 volumio2 volumio[37480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:36 volumio2 volumio[37480]: info: [1750372896355] CoreMusicLibrary::Adding element Last_100 Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:36 volumio2 volumio[37480]: info: [1750372896356] CoreMusicLibrary::Adding element Webradio Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:41:36 volumio2 volumio[37480]: info: Initializing BBC Radios Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: Creating Spotify config file Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: [now-playing] ConfigUpdater: config is up to date. Jun 20 00:41:36 volumio2 sudo[37640]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 00:41:36 volumio2 sudo[37640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 00:41:36 volumio2 sudo[37640]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:36 volumio2 volumio[37480]: info: Loading i18n strings for locale de Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: FusionDsp - mixtype--------------------- None Jun 20 00:41:36 volumio2 volumio[37480]: info: Volumio Calling Home Jun 20 00:41:36 volumio2 volumio[37480]: info: Preparing to generate the ALSA configuration file Jun 20 00:41:36 volumio2 volumio[37480]: info: [now-playing] App is listening on port 4004. Jun 20 00:41:36 volumio2 volumio[37480]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 20 00:41:36 volumio2 volumio[37480]: info: Listing playlists Jun 20 00:41:36 volumio2 volumio[37480]: info: Listing playlists Jun 20 00:41:36 volumio2 volumio[37480]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 00:41:36 volumio2 volumio[37480]: info: Reading ALSA contributions from plugins. Jun 20 00:41:36 volumio2 volumio[37480]: info: MPD Permissions set Jun 20 00:41:36 volumio2 volumio[37480]: info: MPD Permissions set Jun 20 00:41:36 volumio2 volumio[37480]: info: Spotify config file written Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 sudo[37662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 20 00:41:36 volumio2 sudo[37662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jun 20 00:41:36 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:41:36 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 20 00:41:36 volumio2 go-librespot[37673]: go-librespot daemon starting... Jun 20 00:41:36 volumio2 sudo[37662]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: No need to fix Spotify hosts Jun 20 00:41:36 volumio2 volumio[37480]: info: Discovery: adding 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 20 00:41:36 volumio2 volumio[37480]: info: Discovery: Found device Volumio2 Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:36 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:36 volumio2 go-librespot[37680]: time="2025-06-20T00:41:36+02:00" level=info msg="running go-librespot 0.2.0" Jun 20 00:41:36 volumio2 go-librespot[37680]: time="2025-06-20T00:41:36+02:00" level=debug msg="app state loaded" Jun 20 00:41:36 volumio2 go-librespot[37680]: time="2025-06-20T00:41:36+02:00" level=debug msg="stored credentials not found" Jun 20 00:41:36 volumio2 go-librespot[37680]: time="2025-06-20T00:41:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 20 00:41:36 volumio2 volumio[37480]: info: Discovery: this is already registered, 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 20 00:41:36 volumio2 volumio[37480]: info: Discovery: Found device Volumio2 Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:36 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:36 volumio2 volumio[37480]: info: Discovery: Getting this device information Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:36 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:36 volumio2 volumio[37480]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 20 00:41:36 volumio2 volumio[37480]: info: Discovery: Connected to remote: 192.168.1.11 Jun 20 00:41:36 volumio2 volumio[37480]: info: Volumio called home Jun 20 00:41:36 volumio2 volumio[37480]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 20 00:41:36 volumio2 volumio[37480]: info: Starting Shairport Sync Jun 20 00:41:36 volumio2 volumio[37480]: info: Starting Shairport Sync Jun 20 00:41:36 volumio2 volumio[37480]: info: Starting Shairport Sync Jun 20 00:41:36 volumio2 sudo[37688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:36 volumio2 sudo[37688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 sudo[37691]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:36 volumio2 sudo[37691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 20 00:41:36 volumio2 volumio[37480]: info: Asound.conf file unchanged, so no further update is needed Jun 20 00:41:36 volumio2 volumio[37480]: info: Output device has changed, restarting MPD Jun 20 00:41:36 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 20 00:41:36 volumio2 sudo[37693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:36 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 20 00:41:36 volumio2 sudo[37693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:36 volumio2 systemd[1]: shairport-sync.service: Consumed 1.531s CPU time. Jun 20 00:41:36 volumio2 volumio[37480]: info: Output device has changed, restarting Shairport Sync Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:36 volumio2 sudo[37696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:41:36 volumio2 sudo[37696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 sudo[37696]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:36 volumio2 sudo[37701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:41:36 volumio2 volumio[37480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:36 volumio2 sudo[37701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:36 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:36 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 20 00:41:36 volumio2 sudo[37688]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:36 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 20 00:41:36 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:36 volumio2 systemd[1]: shairport-sync.service: Start request repeated too quickly. Jun 20 00:41:36 volumio2 systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Jun 20 00:41:36 volumio2 systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:36 volumio2 sudo[37693]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:36 volumio2 sudo[37691]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:36 volumio2 volumio[37480]: info: MPD Permissions set Jun 20 00:41:36 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:41:36 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:41:36 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:41:36 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:41:36 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:41:36 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:41:36 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:41:36 volumio2 volumio[37480]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Jun 20 00:41:36 volumio2 volumio[37480]: Job for shairport-sync.service failed because start of the service was attempted too often. Jun 20 00:41:36 volumio2 volumio[37480]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Jun 20 00:41:36 volumio2 volumio[37480]: To force a start use "systemctl reset-failed shairport-sync.service" Jun 20 00:41:36 volumio2 volumio[37480]: followed by "systemctl start shairport-sync.service" again. Jun 20 00:41:36 volumio2 volumio[37480]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Jun 20 00:41:36 volumio2 volumio[37480]: Job for shairport-sync.service failed because start of the service was attempted too often. Jun 20 00:41:36 volumio2 volumio[37480]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Jun 20 00:41:36 volumio2 volumio[37480]: To force a start use "systemctl reset-failed shairport-sync.service" Jun 20 00:41:36 volumio2 volumio[37480]: followed by "systemctl start shairport-sync.service" again. Jun 20 00:41:36 volumio2 volumio[37480]: info: Shairport-Sync Started Jun 20 00:41:36 volumio2 volumio[37480]: Error adding Membership: Error: addMembership EINVAL Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:36 volumio2 volumio[37480]: info: Starting Shairport Sync Jun 20 00:41:36 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:36 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:36 volumio2 volumio-remote-updater[827]: [2025-06-20 00:41:36] [connect] Successful connection Jun 20 00:41:36 volumio2 sudo[37720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:36 volumio2 volumio-remote-updater[827]: [2025-06-20 00:41:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1750372896 101 Jun 20 00:41:36 volumio2 sudo[37720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:36 volumio2 volumio[37480]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 Jun 20 00:41:36 volumio2 sudo[37710]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 00:41:36 volumio2 sudo[37710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 00:41:36 volumio2 sudo[37710]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:36 volumio2 systemd[1]: shairport-sync.service: Start request repeated too quickly. Jun 20 00:41:36 volumio2 systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Jun 20 00:41:36 volumio2 systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:36 volumio2 sudo[37720]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:36 volumio2 go-librespot[37680]: time="2025-06-20T00:41:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 20 00:41:36 volumio2 go-librespot[37680]: time="2025-06-20T00:41:36+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 20 00:41:36 volumio2 go-librespot[37680]: time="2025-06-20T00:41:36+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 20 00:41:36 volumio2 volumio[37480]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Jun 20 00:41:36 volumio2 volumio[37480]: Job for shairport-sync.service failed because start of the service was attempted too often. Jun 20 00:41:36 volumio2 volumio[37480]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Jun 20 00:41:36 volumio2 volumio[37480]: To force a start use "systemctl reset-failed shairport-sync.service" Jun 20 00:41:36 volumio2 volumio[37480]: followed by "systemctl start shairport-sync.service" again. Jun 20 00:41:36 volumio2 go-librespot[37680]: time="2025-06-20T00:41:36+02:00" level=info msg="zeroconf server listening on port 42289" Jun 20 00:41:37 volumio2 volumio[37480]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.206 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jun 20 00:41:37 volumio2 volumio[37480]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:37 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 00:41:37 volumio2 volumio[37480]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 20 00:41:37 volumio2 volumio[37480]: info: Received Get System Version Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:41:37 volumio2 volumio[37480]: info: Received Get System Info Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:37 volumio2 volumio[37480]: info: Discovery: Getting this device information Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:37 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:37 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 00:41:37 volumio2 volumio[37480]: info: Received Get System Info Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:37 volumio2 volumio[37480]: info: Discovery: Getting this device information Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:37 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:37 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:37 volumio2 volumio[37480]: info: Listing playlists Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetQueue Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreStateMachine::getQueue Jun 20 00:41:37 volumio2 volumio[37480]: info: CorePlayQueue::getQueue Jun 20 00:41:37 volumio2 mpd[37723]: 2025-06-20T00:41:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 20 00:41:37 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Jun 20 00:41:37 volumio2 sudo[37598]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:37 volumio2 sudo[37701]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:37 volumio2 sudo[37626]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:37 volumio2 volumio[37480]: info: Completed starting Core Plugins Jun 20 00:41:37 volumio2 volumio[37480]: info: ------------------------------------------- Jun 20 00:41:37 volumio2 volumio[37480]: info: ----- MyVolumio plugins startup ---- Jun 20 00:41:37 volumio2 volumio[37480]: info: ------------------------------------------- Jun 20 00:41:37 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 20 00:41:37 volumio2 volumio[37480]: error: MPD error: The expression evaluated to a falsy value: Jun 20 00:41:37 volumio2 volumio[37480]: assert.ok(self.idling) Jun 20 00:41:37 volumio2 volumio[37480]: error: The expression evaluated to a falsy value: Jun 20 00:41:37 volumio2 volumio[37480]: assert.ok(self.idling) Jun 20 00:41:37 volumio2 volumio[37480]: error: MPD error: The expression evaluated to a falsy value: Jun 20 00:41:37 volumio2 volumio[37480]: assert.ok(self.idling) Jun 20 00:41:37 volumio2 volumio[37480]: error: The expression evaluated to a falsy value: Jun 20 00:41:37 volumio2 volumio[37480]: assert.ok(self.idling) Jun 20 00:41:37 volumio2 volumio[37480]: info: MPD running with PID37723 Jun 20 00:41:37 volumio2 volumio[37480]: ,establishing connection Jun 20 00:41:37 volumio2 volumio[37480]: error: updateQueue error: null Jun 20 00:41:37 volumio2 volumio[37480]: error: updateQueue error: null Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 00:41:37 volumio2 volumio[37480]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 00:41:37 volumio2 sudo[37516]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:38 volumio2 volumio[37480]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:38 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:38 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 00:41:38 volumio2 volumio[37480]: info: Received Get System Info Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:38 volumio2 volumio[37480]: info: Discovery: Getting this device information Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:38 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:38 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:38 volumio2 volumio[37480]: info: Listing playlists Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 00:41:38 volumio2 volumio[37480]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 20 00:41:38 volumio2 volumio[37480]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jun 20 00:41:38 volumio2 volumio[37480]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 20 00:41:38 volumio2 volumio[37480]: info: camilladsp service started and running in background, instance 1 Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:38 volumio2 volumio[37480]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Jun 20 00:41:38 volumio2 volumio[37480]: info: FusionDsp loaded Jun 20 00:41:38 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:38 volumio2 volumio[37480]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 20 00:41:38 volumio2 volumio[37480]: info: Adding Signal Path Element [object Object] Jun 20 00:41:38 volumio2 volumio[37480]: info: Adding fusiondspeq DSP Signal Path Element Jun 20 00:41:38 volumio2 volumio[37480]: info: FusionDsp - ---- installed callbackRead Jun 20 00:41:38 volumio2 sudo[37745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 20 00:41:38 volumio2 sudo[37745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:38 volumio2 volumio[37480]: info: FusionDsp - File size found in array! Jun 20 00:41:38 volumio2 volumio[37480]: error: FusionDsp - Reload WebSocket error: [object Object] Jun 20 00:41:38 volumio2 sudo[37745]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:39 volumio2 volumio[37480]: info: go-librespot daemon successfully initialized Jun 20 00:41:42 volumio2 volumio[37480]: info: Initializing connection to go-librespot Websocket Jun 20 00:41:42 volumio2 go-librespot[37680]: time="2025-06-20T00:41:42+02:00" level=debug msg="new websocket client" Jun 20 00:41:42 volumio2 volumio[37480]: info: Connection to go-librespot Websocket established Jun 20 00:41:44 volumio2 sudo[37771]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 00:41:44 volumio2 sudo[37771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:44 volumio2 sudo[37773]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 00:41:44 volumio2 sudo[37773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:44 volumio2 sudo[37771]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:44 volumio2 sudo[37773]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:44 volumio2 sudo[37776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 20 00:41:44 volumio2 sudo[37776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:44 volumio2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 20 00:41:44 volumio2 sudo[37776]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:44 volumio2 volumio[37480]: info: Upmpdcli Daemon Started Jun 20 00:41:44 volumio2 upmpdcli[37794]: writing RSA key Jun 20 00:41:45 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:45 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:45 volumio2 volumio[37480]: info: Getting Spotify volume Jun 20 00:41:45 volumio2 volumio[37480]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 20 00:41:45 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:45 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 20 00:41:45 volumio2 volumio[37480]: info: Adding plugin bluetooth to MyMusic Plugins Jun 20 00:41:45 volumio2 volumio[37480]: info: Adding plugin multiroom to MyMusic Plugins Jun 20 00:41:45 volumio2 volumio[37480]: info: Adding plugin metavolumio to MyMusic Plugins Jun 20 00:41:45 volumio2 volumio[37480]: info: Adding plugin cd_controller to MyMusic Plugins Jun 20 00:41:45 volumio2 volumio[37480]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 20 00:41:45 volumio2 volumio[37480]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 20 00:41:45 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 20 00:41:46 volumio2 volumio[37480]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 20 00:41:46 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 20 00:41:46 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:46 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:46 volumio2 volumio[37480]: info: Starting MyVolumio Remote Streaming Endpoints Jun 20 00:41:46 volumio2 volumio[37480]: info: MyVolumio login type: Token Jun 20 00:41:46 volumio2 volumio[37480]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 20 00:41:46 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 20 00:41:46 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 20 00:41:46 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 20 00:41:46 volumio2 volumio[37480]: info: Streaming services startup Jun 20 00:41:46 volumio2 volumio[37480]: info: Starting Streaming Daemon Jun 20 00:41:46 volumio2 sudo[37802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 20 00:41:46 volumio2 sudo[37802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:46 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 20 00:41:46 volumio2 sudo[37802]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:46 volumio2 volumio[37480]: error: Cannot start Volumio Streaming Daemon Jun 20 00:41:46 volumio2 volumio[37480]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 20 00:41:46 volumio2 volumio[37480]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 20 00:41:46 volumio2 volumio[37480]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 20 00:41:47 volumio2 volumio[37480]: info: MyVolumio token set successfully Jun 20 00:41:47 volumio2 volumio[37480]: info: MYVOLUMIO: Adding device Jun 20 00:41:47 volumio2 volumio[37480]: info: MYVOLUMIO: Evaluating Server Jun 20 00:41:47 volumio2 volumio[37480]: info: MyVolumio Plan changed: premium Jun 20 00:41:47 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 20 00:41:47 volumio2 volumio[37480]: info: Removing browser output: myVolumio user plan is not superstar Jun 20 00:41:47 volumio2 volumio[37480]: info: Removing audio output: Jun 20 00:41:47 volumio2 volumio[37480]: info: MYVOLUMIO: Adding device Jun 20 00:41:47 volumio2 volumio[37480]: info: MYVOLUMIO: Evaluating Server Jun 20 00:41:47 volumio2 volumio[37480]: info: Remote config written successfully Jun 20 00:41:47 volumio2 volumio[37480]: info: Starting Tunnel 1 Jun 20 00:41:47 volumio2 volumio[37480]: info: Starting Tunnel Connection Checker Jun 20 00:41:47 volumio2 volumio[37480]: info: Completed starting MyVolumio Plugin Jun 20 00:41:47 volumio2 volumio[37480]: info: MYVolumio Device enabled Jun 20 00:41:47 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 20 00:41:47 volumio2 volumio[37480]: info: MyVolumio status changed Jun 20 00:41:47 volumio2 volumio[37480]: info: Streaming services startup Jun 20 00:41:47 volumio2 volumio[37480]: info: Starting Streaming Daemon Jun 20 00:41:48 volumio2 sudo[37842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 20 00:41:48 volumio2 sudo[37842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 20 00:41:48 volumio2 sudo[37842]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 volumio[37480]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 20 00:41:48 volumio2 sudo[37846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 20 00:41:48 volumio2 sudo[37846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 sudo[37846]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 volumio[37480]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: MultiRoom plugin initialized Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: STOPPING SNAPCLIENT Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: Snap server stop Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: STOPPING volumioStreaming Jun 20 00:41:48 volumio2 sudo[37864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 20 00:41:48 volumio2 sudo[37864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 sudo[37866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 20 00:41:48 volumio2 sudo[37866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 sudo[37869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 20 00:41:48 volumio2 sudo[37869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 20 00:41:48 volumio2 sudo[37872]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 20 00:41:48 volumio2 sudo[37872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 sudo[37872]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 20 00:41:48 volumio2 sudo[37864]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 20 00:41:48 volumio2 sudo[37866]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 20 00:41:48 volumio2 sudo[37869]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 20 00:41:48 volumio2 volumio[37480]: info: Preparing to generate the ALSA configuration file Jun 20 00:41:48 volumio2 volumio[37480]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 20 00:41:48 volumio2 volumio[37480]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 00:41:48 volumio2 volumio[37480]: info: Reading ALSA contributions from plugins. Jun 20 00:41:48 volumio2 volumio[37480]: info: Setting Geolocation for MyVolumio to eu6 Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: Setting Geolocation for MyVolumio to eu4 Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: Removed streaming files Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: volumioStreaming STOPPED Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: SNAPSERVER STOPPED Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: SNAPCLIENT STOPPED Jun 20 00:41:48 volumio2 volumio[37480]: error: Cannot start Volumio Streaming Daemon Jun 20 00:41:48 volumio2 volumio[37480]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 20 00:41:48 volumio2 volumio[37480]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 20 00:41:48 volumio2 volumio[37480]: info: Asound.conf file written Jun 20 00:41:48 volumio2 sudo[37877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 20 00:41:48 volumio2 sudo[37877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 sudo[37877]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 volumio[37480]: info: Output device has changed, restarting MPD Jun 20 00:41:48 volumio2 volumio[37480]: info: Output device has changed, restarting Shairport Sync Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:48 volumio2 sudo[37883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:41:48 volumio2 sudo[37883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 sudo[37883]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 sudo[37886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:41:48 volumio2 volumio[37480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:41:48 volumio2 sudo[37886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jun 20 00:41:48 volumio2 volumio[37480]: ------------------------------------ BT MESSAGE: [FUNC] onStart Jun 20 00:41:48 volumio2 volumio[37480]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Jun 20 00:41:48 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 20 00:41:48 volumio2 volumio[37480]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Jun 20 00:41:48 volumio2 volumio[37480]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: Pushing multiroomSync output for this device Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: Pushing multiroomSync output Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:48 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:41:48 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:41:48 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:41:48 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:41:48 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 bluetoothd[911]: Path / reserved for Adv Monitor app :1.384 Jun 20 00:41:48 volumio2 bluetoothd[911]: Adv Monitor app :1.384 disconnected from D-Bus Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding METAVOLUMIO REST API Endpoints Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jun 20 00:41:48 volumio2 volumio[37480]: info: Preparing CD Folders Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding CD REST API Endpoints Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 20 00:41:48 volumio2 volumio[37480]: info: Starting UDEV Watcher for CD Jun 20 00:41:48 volumio2 volumio[37480]: info: Detecting CD presence with UDEV Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jun 20 00:41:48 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:41:48 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding inputs REST Endpoints Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jun 20 00:41:48 volumio2 volumio[37480]: info: Scanning Audio Inputs Jun 20 00:41:48 volumio2 volumio[37480]: info: Checking against Known Cards name Jun 20 00:41:48 volumio2 volumio[37480]: info: Checking against Known Cards name Jun 20 00:41:48 volumio2 volumio[37480]: info: Checking against Known Cards name Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:48 volumio2 volumio[37480]: info: [1750372908667] CoreMusicLibrary::Adding element USB Audio CODEC Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:48 volumio2 volumio[37480]: Cannot find translation for source USB Audio CODEC Jun 20 00:41:48 volumio2 volumio[37480]: info: Checking against Known Cards name Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding Server instance for streaming Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jun 20 00:41:48 volumio2 volumio[37480]: error: Hi Res Audio Failed Login: Missing Login Data Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jun 20 00:41:48 volumio2 volumio[37480]: info: Refreshing TIDAL token Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jun 20 00:41:48 volumio2 volumio[37480]: info: Refreshing QOBUZ token Jun 20 00:41:48 volumio2 volumio[37480]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jun 20 00:41:48 volumio2 sudo[37905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 20 00:41:48 volumio2 sudo[37905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 20 00:41:48 volumio2 sudo[37900]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 00:41:48 volumio2 systemd[1]: vtcs.service: Killing process 34421 (vtcs) with signal SIGKILL. Jun 20 00:41:48 volumio2 sudo[37900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 00:41:48 volumio2 systemd[1]: vtcs.service: Deactivated successfully. Jun 20 00:41:48 volumio2 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: Adding multiroomSync output Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: Pushing multiroomSync output Jun 20 00:41:48 volumio2 sudo[37900]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 systemd[1]: vtcs.service: Consumed 1.020s CPU time. Jun 20 00:41:48 volumio2 volumio[37480]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Jun 20 00:41:48 volumio2 volumio[37480]: info: MPD Permissions set Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 sudo[37910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 20 00:41:48 volumio2 sudo[37910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 sudo[37905]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:41:48 volumio2 sudo[37910]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:48 volumio2 volumio[37480]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::volumioStop Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreStateMachine::stop Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 00:41:48 volumio2 volumio[37480]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 20 00:41:48 volumio2 volumio[37480]: info: Starting Shairport Sync Jun 20 00:41:48 volumio2 volumio[37480]: info: Access Token successfully retrieved Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:48 volumio2 volumio[37480]: info: [1750372908802] CoreMusicLibrary::Adding element QOBUZ Jun 20 00:41:48 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:48 volumio2 volumio[37480]: Cannot find translation for source USB Audio CODEC Jun 20 00:41:48 volumio2 volumio[37480]: Cannot find translation for source QOBUZ Jun 20 00:41:48 volumio2 sudo[37924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:41:48 volumio2 sudo[37924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:48 volumio2 volumio[37480]: info: Stopping AccessToken refresher cron for QOBUZ Jun 20 00:41:48 volumio2 volumio[37480]: info: AccessToken refresher cron started for QOBUZ Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding QOBUZ REST API Endpoints Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: Found cast device: LenovoCD-24502F-1797cd035674a6bd04799d93772b0005 Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:48 volumio2 volumio[37480]: info: MRS: Found cast device: Pioneer-SX-S30DAB-92929d1214fa9756f0b255389524de8b Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:48 volumio2 volumio[37480]: info: Successfully Added MyVolumio device Jun 20 00:41:48 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:41:48 volumio2 sudo[37924]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:48 volumio2 volumio[37480]: info: Shairport-Sync Started Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:48 volumio2 volumio[37480]: info: Access Token successfully retrieved Jun 20 00:41:48 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:49 volumio2 volumio[37480]: info: Adding audio output: Jun 20 00:41:49 volumio2 volumio[37480]: info: Successfully retrieved User Session From TIDAL Jun 20 00:41:49 volumio2 volumio[37480]: info: Successfully retrieved User Subscription From TIDAL Jun 20 00:41:49 volumio2 volumio[37480]: info: Adding TIDAL to Browse Sources Jun 20 00:41:49 volumio2 volumio[37480]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:41:49 volumio2 volumio[37480]: info: [1750372909270] CoreMusicLibrary::Adding element TIDAL Jun 20 00:41:49 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:49 volumio2 volumio[37480]: Cannot find translation for source USB Audio CODEC Jun 20 00:41:49 volumio2 volumio[37480]: Cannot find translation for source QOBUZ Jun 20 00:41:49 volumio2 volumio[37480]: Cannot find translation for source TIDAL Jun 20 00:41:49 volumio2 volumio[37480]: info: Stopping AccessToken refresher cron Jun 20 00:41:49 volumio2 volumio[37480]: info: AccessToken refresher cron started Jun 20 00:41:49 volumio2 volumio[37480]: info: Adding TIDAL REST API Endpoints Jun 20 00:41:49 volumio2 mpd[37908]: 2025-06-20T00:41:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 20 00:41:49 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Jun 20 00:41:49 volumio2 sudo[37886]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:49 volumio2 volumio[37480]: error: updateQueue error: null Jun 20 00:41:49 volumio2 volumio[37480]: info: Successfully Added MyVolumio device Jun 20 00:41:49 volumio2 volumio[37480]: info: Updating MyVolumio device info Jun 20 00:41:49 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:49 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:49 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:49 volumio2 volumio[37480]: info: Updating MyVolumio device info Jun 20 00:41:49 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:49 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:49 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:49 volumio2 volumio[37480]: info: Successfully Updated MyVolumio device Jun 20 00:41:49 volumio2 volumio[37480]: info: Successfully Updated MyVolumio device Jun 20 00:41:51 volumio2 volumio[37480]: info: TidalConnect service stoped! Jun 20 00:41:51 volumio2 volumio[37480]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 20 00:41:51 volumio2 volumio[37480]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 20 00:41:51 volumio2 sudo[37955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 20 00:41:51 volumio2 sudo[37955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:51 volumio2 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 20 00:41:51 volumio2 sudo[37955]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:51 volumio2 volumio[37480]: info: Executing endpoint tc_getconfig Jun 20 00:41:51 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 20 00:41:51 volumio2 vtcs[37958]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 20 00:41:51 volumio2 vtcs[37958]: STARTING TidalConnect services, version: 1.5.0.45 Jun 20 00:41:51 volumio2 vtcs[37958]: STARTED TidalConnect services. Jun 20 00:41:51 volumio2 volumio[37480]: info: Executing endpoint tc_connect Jun 20 00:41:51 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 20 00:41:51 volumio2 volumio[37480]: info: Connecting to TidalConnect Jun 20 00:41:51 volumio2 volumio[37480]: info: CoreCommandRouter::servicePushState Jun 20 00:41:51 volumio2 volumio[37480]: info: CoreStateMachine::pushState Jun 20 00:41:51 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 00:41:51 volumio2 volumio[37480]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:51 volumio2 volumio[37480]: info: MRS: Pushing multiroomSync output update for this device Jun 20 00:41:51 volumio2 volumio[37480]: info: MRS: Pushing multiroomSync output Jun 20 00:41:51 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:51 volumio2 volumio[37480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jun 20 00:41:51 volumio2 volumio[37480]: info: CoreCommandRouter::servicePushState Jun 20 00:41:51 volumio2 volumio[37480]: info: CoreStateMachine::pushState Jun 20 00:41:51 volumio2 volumio[37480]: info: CoreCommandRouter::volumioPushState Jun 20 00:41:51 volumio2 volumio[37480]: info: MRS: Pushing multiroomSync output update for this device Jun 20 00:41:51 volumio2 volumio[37480]: info: MRS: Pushing multiroomSync output Jun 20 00:41:51 volumio2 volumio[37480]: info: CorePlayQueue::getTrack 0 Jun 20 00:41:51 volumio2 volumio[37480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Jun 20 00:41:51 volumio2 vtcs[37958]: [2025-06-20 00:41:51.942] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 20 00:41:51 volumio2 volumio[37480]: info: FusionDsp - Volumio is not playing Jun 20 00:41:51 volumio2 volumio[37480]: info: FusionDsp - Clipped samples monitor stopped Jun 20 00:41:51 volumio2 volumio[37480]: info: FusionDsp - Volumio is not playing Jun 20 00:41:51 volumio2 volumio[37480]: info: FusionDsp - Clipped samples monitor stopped Jun 20 00:41:52 volumio2 sudo[37982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 20 00:41:52 volumio2 sudo[37982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:52 volumio2 autossh[34446]: received signal to exit (15) Jun 20 00:41:52 volumio2 systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Jun 20 00:41:52 volumio2 systemd[1]: sshtunnel.service: Deactivated successfully. Jun 20 00:41:52 volumio2 systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 00:41:52 volumio2 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 20 00:41:52 volumio2 sudo[37982]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:52 volumio2 volumio[37480]: info: Remote SSH Started Jun 20 00:41:52 volumio2 autossh[37985]: port set to 0, monitoring disabled Jun 20 00:41:52 volumio2 autossh[37985]: starting ssh (count 1) Jun 20 00:41:52 volumio2 autossh[37985]: ssh child pid is 37988 Jun 20 00:41:52 volumio2 volumio[37480]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jun 20 00:41:52 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:52 volumio2 volumio[37480]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KmhI4VNLmgfYPsYBmUr34lKfrfO2.25c24366becc66fc5dbb8e71214777ce.state.status' Jun 20 00:41:52 volumio2 volumiossh-tunnel[37988]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Jun 20 00:41:53 volumio2 volumio[37480]: info: MRS: Getting audio outputs on start Jun 20 00:41:53 volumio2 volumio[37480]: info: MRS: Requesting all other devices output Jun 20 00:41:53 volumio2 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 20 00:41:53 volumio2 volumio[37480]: info: Executing endpoint metavolumio Jun 20 00:41:53 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 20 00:41:54 volumio2 volumio[37480]: info: TidalConnect service started! Jun 20 00:41:54 volumio2 volumio[37480]: [Metrics] CommandRouter: 20s 995.52ms Jun 20 00:41:54 volumio2 volumio[37480]: info: CoreCommandRouter::volumiosetStartupVolume Jun 20 00:41:54 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:41:54 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:41:54 volumio2 volumio[37480]: info: CoreCommandRouter::Close All Modals sent Jun 20 00:41:54 volumio2 volumio[37480]: info: CoreCommandRouter::Close All Modals sent Jun 20 00:41:54 volumio2 volumio[37480]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 20 00:41:54 volumio2 volumio[37480]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 20 00:41:54 volumio2 volumio[37480]: info: camilladsp stopping service pid 37738... Jun 20 00:41:55 volumio2 volumio[37480]: grep: /proc/37738/cmdline: binary file matches Jun 20 00:41:55 volumio2 volumio[37480]: grep: /proc/37738/cmdline: binary file matches Jun 20 00:41:55 volumio2 volumio[37480]: grep: /proc/37738/cmdline: binary file matches Jun 20 00:41:55 volumio2 volumio[37480]: grep: /proc/37738/cmdline: binary file matches Jun 20 00:41:55 volumio2 volumio[37480]: grep: /proc/37738/cmdline: binary file matches Jun 20 00:41:55 volumio2 volumio[37480]: info: camilladsp service terminated, instance 1 Jun 20 00:41:55 volumio2 volumio[37480]: info: FusionDsp - File size found in array! Jun 20 00:41:55 volumio2 volumio[37480]: info: camilladsp service started and running in background, instance 1 Jun 20 00:41:55 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:55 volumio2 volumio[37480]: info: Listing playlists Jun 20 00:41:55 volumio2 volumio[37480]: info: Listing playlists Jun 20 00:41:55 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 20 00:41:55 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 20 00:41:55 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 20 00:41:56 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:57 volumio2 sudo[38046]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 00:41:57 volumio2 sudo[38046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:57 volumio2 sudo[38046]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:57 volumio2 sudo[38048]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 00:41:57 volumio2 sudo[38048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:57 volumio2 sudo[38048]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:57 volumio2 volumio[37480]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jun 20 00:41:57 volumio2 sudo[38052]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 00:41:57 volumio2 sudo[38052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:57 volumio2 sudo[38052]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:57 volumio2 sudo[38054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 00:41:57 volumio2 sudo[38054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:41:57 volumio2 sudo[38054]: pam_unix(sudo:session): session closed for user root Jun 20 00:41:57 volumio2 volumio[37480]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetVisibleSources Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetQueue Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreStateMachine::getQueue Jun 20 00:41:57 volumio2 volumio[37480]: info: CorePlayQueue::getQueue Jun 20 00:41:57 volumio2 volumio[37480]: info: Listing playlists Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 20 00:41:57 volumio2 volumio[37480]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 20 00:41:57 volumio2 volumio[37480]: info: Received Get System Info Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:57 volumio2 volumio[37480]: info: Discovery: Getting this device information Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 20 00:41:57 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 00:41:59 volumio2 volumio[37480]: info: Received Get System Info Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:59 volumio2 volumio[37480]: info: Discovery: Getting this device information Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 20 00:41:59 volumio2 volumio[37480]: info: Received Get System Info Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:41:59 volumio2 volumio[37480]: info: Discovery: Getting this device information Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:41:59 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 20 00:42:01 volumio2 volumio[37480]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::volumioGetState Jun 20 00:42:01 volumio2 volumio[37480]: Searching all installed plugins Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: , search Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 20 00:42:01 volumio2 volumio[37480]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: smart_inputs , search Jun 20 00:42:01 volumio2 volumio[37480]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin smart_inputs Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jun 20 00:42:01 volumio2 volumio[37480]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 20 00:42:01 volumio2 volumio[37480]: Use XMLElement.setAttribute instead Jun 20 00:42:01 volumio2 volumio[37480]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 00:42:01 volumio2 volumio[37480]: TypeError: children[i].attr(...).value is not a function Jun 20 00:42:01 volumio2 volumio[37480]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 20 00:42:01 volumio2 volumio[37480]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 20 00:42:01 volumio2 volumio[37480]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 00:42:01 volumio2 sudo[38080]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-20 00:41' Jun 20 00:42:01 volumio2 sudo[38080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:01 volumio2 sudo[38080]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:01 volumio2 vtcs[37958]: [2025-06-20 00:42:01.995] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 20 00:42:01 volumio2 volumio-remote-updater[827]: [2025-06-20 00:42:01] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Jun 20 00:42:01 volumio2 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 20 00:42:01 volumio2 go-librespot[37680]: time="2025-06-20T00:42:01+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 20 00:42:01 volumio2 volumio-remote-updater[827]: [2025-06-20 00:42:01] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Jun 20 00:42:02 volumio2 systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 20 00:42:02 volumio2 systemd[1]: volumio.service: Consumed 13.262s CPU time. Jun 20 00:42:02 volumio2 systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 20 00:42:02 volumio2 systemd[1]: dynamicswap.service: Deactivated successfully. Jun 20 00:42:02 volumio2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Jun 20 00:42:02 volumio2 systemd[1]: Started dynamicswap.service - dynamicswap service. Jun 20 00:42:02 volumio2 systemd[1]: Stopped volumio.service - Volumio Backend Module. Jun 20 00:42:02 volumio2 systemd[1]: volumio.service: Consumed 13.262s CPU time. Jun 20 00:42:02 volumio2 systemd[1]: Started volumio.service - Volumio Backend Module. Jun 20 00:42:02 volumio2 systemd[1]: dynamicswap.service: Deactivated successfully. Jun 20 00:42:02 volumio2 volumio[38100]: info: ------------------------------------------- Jun 20 00:42:02 volumio2 volumio[38100]: info: ----- Volumio3 ---- Jun 20 00:42:02 volumio2 volumio[38100]: info: ------------------------------------------- Jun 20 00:42:02 volumio2 volumio[38100]: info: ----- System startup ---- Jun 20 00:42:02 volumio2 volumio[38100]: info: ------------------------------------------- Jun 20 00:42:03 volumio2 volumio[38100]: info: MYVOLUMIO Environment detected Jun 20 00:42:03 volumio2 volumio[38100]: info: Plugin folders cleanup Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning into folder /volumio/app/plugins/ Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning category audio_interface Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning category miscellanea Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning category music_service Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning category plugins.json Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning category system_controller Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning category user_interface Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning into folder /data/plugins/ Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning category audio_interface Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning category music_service Jun 20 00:42:03 volumio2 volumio[38100]: info: Scanning category user_interface Jun 20 00:42:03 volumio2 volumio[38100]: info: Plugin folders cleanup completed Jun 20 00:42:03 volumio2 volumio[38100]: info: ------------------------------------------- Jun 20 00:42:03 volumio2 volumio[38100]: info: ----- Core plugins startup ---- Jun 20 00:42:03 volumio2 volumio[38100]: info: ------------------------------------------- Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugins from folder /volumio/app/plugins/ Jun 20 00:42:03 volumio2 volumio[38100]: info: Adding plugin upnp to MyMusic Plugins Jun 20 00:42:03 volumio2 volumio[38100]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 20 00:42:03 volumio2 volumio[38100]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugins from folder /data/plugins/ Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "system"... Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "appearance"... Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "network"... Jun 20 00:42:03 volumio2 volumio[38100]: info: Refreshing Cached IP Addresses Jun 20 00:42:03 volumio2 sudo[38129]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 20 00:42:03 volumio2 sudo[38129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:03 volumio2 sudo[38131]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 20 00:42:03 volumio2 sudo[38131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "services"... Jun 20 00:42:03 volumio2 sudo[38129]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "alsa_controller"... Jun 20 00:42:03 volumio2 sudo[38131]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:03 volumio2 sudo[38138]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 20 00:42:03 volumio2 sudo[38138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:03 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "wizard"... Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "networkfs"... Jun 20 00:42:03 volumio2 volumio[38100]: info: Starting Udev Watcher for removable devices Jun 20 00:42:03 volumio2 volumio[38100]: info: Ignoring mount for partition: boot Jun 20 00:42:03 volumio2 volumio[38100]: info: Ignoring mount for partition: volumio Jun 20 00:42:03 volumio2 volumio[38100]: info: Ignoring mount for partition: volumio_data Jun 20 00:42:03 volumio2 volumio[38100]: info: Ignoring mount for partition: boot Jun 20 00:42:03 volumio2 volumio[38100]: info: Mounting Device SWEEP_FILE Jun 20 00:42:03 volumio2 sudo[38165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/SWEEP_FILE -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 20 00:42:03 volumio2 sudo[38165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:03 volumio2 sudo[38165]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:03 volumio2 volumio[38100]: mount: /media/SWEEP_FILE: /dev/sdb2 already mounted on /media/SWEEP_FILE. Jun 20 00:42:03 volumio2 volumio[38100]: dmesg(1) may have more information after failed mount system call. Jun 20 00:42:03 volumio2 volumio[38100]: error: Failed to mount SWEEP_FILE: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb2" "/mnt/USB/SWEEP_FILE" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 20 00:42:03 volumio2 volumio[38100]: mount: /media/SWEEP_FILE: /dev/sdb2 already mounted on /media/SWEEP_FILE. Jun 20 00:42:03 volumio2 volumio[38100]: dmesg(1) may have more information after failed mount system call. Jun 20 00:42:03 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "volumio_command_line_client"... Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "upnp"... Jun 20 00:42:03 volumio2 volumio[38100]: info: [1750372923417] Starting Upmpd Daemon Jun 20 00:42:03 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "my_music"... Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "mpd"... Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "upnp_browser"... Jun 20 00:42:03 volumio2 volumio[38100]: info: Loading plugin "alarm-clock"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "airplay_emulation"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Starting Shairport Sync Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "last_100"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "webradio"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "i2s_dacs"... Jun 20 00:42:04 volumio2 volumio[38100]: info: I2S DAC not set, start Auto-detection Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "volumiodiscovery"... Jun 20 00:42:04 volumio2 volumio[38100]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 20 00:42:04 volumio2 volumio[38100]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:42:04 volumio2 volumio[38100]: *** WARNING *** For more information see Jun 20 00:42:04 volumio2 volumio[38100]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 20 00:42:04 volumio2 volumio[38100]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:42:04 volumio2 node[38100]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 20 00:42:04 volumio2 volumio[38100]: *** WARNING *** For more information see Jun 20 00:42:04 volumio2 node[38100]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:42:04 volumio2 node[38100]: *** WARNING *** For more information see Jun 20 00:42:04 volumio2 node[38100]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 20 00:42:04 volumio2 node[38100]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 20 00:42:04 volumio2 node[38100]: *** WARNING *** For more information see Jun 20 00:42:04 volumio2 volumio[38100]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 20 00:42:04 volumio2 volumio[38100]: info: Discovery: Started advertising with name: Volumio2 Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "spop"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "now_playing"... Jun 20 00:42:04 volumio2 sudo[38138]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "outputs"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "albumart"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Plugin example_plugin is not enabled Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "inputs"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "updater_comm"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Plugin mpdemulation is not enabled Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "rest_api"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "websocket"... Jun 20 00:42:04 volumio2 volumio[38100]: info: Starting Socket.io Server version 1.7.4 Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading plugin "fusiondsp"... Jun 20 00:42:04 volumio2 volumio[38171]: Forking 3 albumart workers Jun 20 00:42:04 volumio2 volumio[38100]: info: Applying required configuration parameters for plugin fusiondsp Jun 20 00:42:04 volumio2 volumio[38100]: info: Loading i18n strings for locale de Jun 20 00:42:04 volumio2 volumio[38100]: Updating browse sources language Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::initPlayerControls Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: Express server listening on port 3000 Jun 20 00:42:04 volumio2 volumio[38100]: [Metrics] WebUI: 2s 213.98ms Jun 20 00:42:04 volumio2 volumio[38100]: info: Setting Device type: x86 Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreStateMachine::resetVolumioState Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreStateMachine::getcurrentVolume Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::volumioRetrievevolume Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreStateMachine::pushState Jun 20 00:42:04 volumio2 volumio[38100]: info: CorePlayQueue::getTrack 0 Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::volumioPushState Jun 20 00:42:04 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:42:04 volumio2 volumio[38100]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 20 00:42:04 volumio2 volumio[38100]: info: Completed loading Core Plugins Jun 20 00:42:04 volumio2 volumio[38100]: info: Preparing to generate the ALSA configuration file Jun 20 00:42:04 volumio2 volumio[38100]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 00:42:04 volumio2 volumio[38100]: info: Reading ALSA contributions from plugins. Jun 20 00:42:04 volumio2 volumio[38100]: info: Volumio Network Manager: Network status updated: 2 Jun 20 00:42:04 volumio2 volumio[38100]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 20 00:42:04 volumio2 volumio[38100]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.206 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jun 20 00:42:04 volumio2 volumio[38183]: Starting albumart workers Jun 20 00:42:04 volumio2 volumio[38181]: Starting albumart workers Jun 20 00:42:05 volumio2 volumio[38182]: Starting albumart workers Jun 20 00:42:05 volumio2 volumio[38100]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 20 00:42:05 volumio2 volumio[38100]: info: Reloading queue from file Jun 20 00:42:05 volumio2 volumio[38100]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreStateMachine::setRepeat null single undefined Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreStateMachine::pushState Jun 20 00:42:05 volumio2 volumio[38100]: info: CorePlayQueue::getTrack 0 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioPushState Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreStateMachine::setRandom null Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreStateMachine::pushState Jun 20 00:42:05 volumio2 volumio[38100]: info: CorePlayQueue::getTrack 0 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioPushState Jun 20 00:42:05 volumio2 volumio[38100]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 20 00:42:05 volumio2 volumio[38100]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.11 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 20 00:42:05 volumio2 volumio[38100]: info: Discovery: adding 04720723-0b84-4186-8f09-f9ac9b9f00f9 Jun 20 00:42:05 volumio2 volumio[38100]: info: Discovery: Found device Volumio-AZ Jun 20 00:42:05 volumio2 volumio[38100]: info: Discovery: Connecting to remote: 192.168.1.11 Jun 20 00:42:05 volumio2 volumio[38100]: info: Discovery: Connected to remote: 192.168.1.11 Jun 20 00:42:05 volumio2 volumio[38100]: info: Discovery: adding 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 20 00:42:05 volumio2 volumio[38100]: info: Discovery: Found device Volumio2 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioGetState Jun 20 00:42:05 volumio2 volumio[38100]: info: CorePlayQueue::getTrack 0 Jun 20 00:42:05 volumio2 volumio[38100]: info: Discovery: this is already registered, 6b56f2d5-cbd9-4eb4-af79-a9ffa3dae30f Jun 20 00:42:05 volumio2 volumio[38100]: info: Discovery: Found device Volumio2 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioGetState Jun 20 00:42:05 volumio2 volumio[38100]: info: CorePlayQueue::getTrack 0 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 20 00:42:05 volumio2 volumio[38100]: info: Asound.conf file written Jun 20 00:42:05 volumio2 sudo[38222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 20 00:42:05 volumio2 sudo[38222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 sudo[38222]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 volumio[38100]: info: Output device has changed, restarting MPD Jun 20 00:42:05 volumio2 volumio[38100]: info: Output device has changed, restarting Shairport Sync Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:42:05 volumio2 sudo[38243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:42:05 volumio2 sudo[38243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 sudo[38243]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 sudo[38248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:42:05 volumio2 sudo[38248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 volumio[38100]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:42:05 volumio2 volumio[38100]: info: ___________ START PLUGINS ___________ Jun 20 00:42:05 volumio2 volumio[38100]: info: ControllerMpd::onStart: Initializing MPD Jun 20 00:42:05 volumio2 volumio[38100]: info: Creating MPD Configuration file Jun 20 00:42:05 volumio2 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:42:05 volumio2 volumio[38100]: info: [1750372925268] CoreMusicLibrary::Adding element Medienserver Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:42:05 volumio2 sudo[38256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:42:05 volumio2 sudo[38256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:42:05 volumio2 sudo[38256]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:42:05 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:42:05 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:42:05 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:42:05 volumio2 sudo[38258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:42:05 volumio2 sudo[38258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 volumio[38100]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:42:05 volumio2 volumio[38100]: info: [1750372925299] CoreMusicLibrary::Adding element Last_100 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 20 00:42:05 volumio2 volumio[38100]: info: [1750372925300] CoreMusicLibrary::Adding element Webradio Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:42:05 volumio2 volumio[38100]: info: Initializing BBC Radios Jun 20 00:42:05 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:42:05 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:42:05 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:42:05 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:42:05 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:42:05 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:42:05 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:42:05 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:42:05 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: Creating Spotify config file Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: [now-playing] ConfigUpdater: config is up to date. Jun 20 00:42:05 volumio2 volumio[38100]: info: Loading i18n strings for locale de Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: FusionDsp - mixtype--------------------- None Jun 20 00:42:05 volumio2 volumio[38100]: info: Volumio Calling Home Jun 20 00:42:05 volumio2 volumio[38100]: info: Preparing to generate the ALSA configuration file Jun 20 00:42:05 volumio2 sudo[38284]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 00:42:05 volumio2 sudo[38284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 00:42:05 volumio2 sudo[38284]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 volumio[38100]: info: [now-playing] App is listening on port 4004. Jun 20 00:42:05 volumio2 volumio[38100]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioGetState Jun 20 00:42:05 volumio2 volumio[38100]: info: CorePlayQueue::getTrack 0 Jun 20 00:42:05 volumio2 volumio[38100]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 20 00:42:05 volumio2 volumio[38100]: info: Reading ALSA contributions from plugins. Jun 20 00:42:05 volumio2 volumio[38100]: info: MPD Permissions set Jun 20 00:42:05 volumio2 volumio[38100]: info: MPD Permissions set Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 20 00:42:05 volumio2 volumio[38100]: info: Discovery: Getting this device information Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioGetState Jun 20 00:42:05 volumio2 volumio[38100]: info: CorePlayQueue::getTrack 0 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 20 00:42:05 volumio2 volumio[38100]: info: Volumio called home Jun 20 00:42:05 volumio2 volumio[38100]: info: Spotify config file written Jun 20 00:42:05 volumio2 volumio[38100]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 sudo[38293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 20 00:42:05 volumio2 sudo[38293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:42:05 volumio2 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jun 20 00:42:05 volumio2 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jun 20 00:42:05 volumio2 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:42:05 volumio2 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 20 00:42:05 volumio2 sudo[38293]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 go-librespot[38303]: go-librespot daemon starting... Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: No need to fix Spotify hosts Jun 20 00:42:05 volumio2 go-librespot[38312]: time="2025-06-20T00:42:05+02:00" level=info msg="running go-librespot 0.2.0" Jun 20 00:42:05 volumio2 go-librespot[38312]: time="2025-06-20T00:42:05+02:00" level=debug msg="app state loaded" Jun 20 00:42:05 volumio2 go-librespot[38312]: time="2025-06-20T00:42:05+02:00" level=debug msg="stored credentials not found" Jun 20 00:42:05 volumio2 go-librespot[38312]: time="2025-06-20T00:42:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 20 00:42:05 volumio2 volumio[38100]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 20 00:42:05 volumio2 volumio[38100]: verbose: New Socket.io Connection to 192.168.1.215:3000 from 192.168.1.206 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Jun 20 00:42:05 volumio2 volumio[38100]: info: Starting Shairport Sync Jun 20 00:42:05 volumio2 volumio[38100]: info: Starting Shairport Sync Jun 20 00:42:05 volumio2 volumio[38100]: info: Starting Shairport Sync Jun 20 00:42:05 volumio2 sudo[38320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:42:05 volumio2 sudo[38320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioGetState Jun 20 00:42:05 volumio2 volumio[38100]: info: CorePlayQueue::getTrack 0 Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioGetState Jun 20 00:42:05 volumio2 volumio[38100]: info: CorePlayQueue::getTrack 0 Jun 20 00:42:05 volumio2 go-librespot[38312]: time="2025-06-20T00:42:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 20 00:42:05 volumio2 go-librespot[38312]: time="2025-06-20T00:42:05+02: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]" Jun 20 00:42:05 volumio2 go-librespot[38312]: time="2025-06-20T00:42:05+02: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]" Jun 20 00:42:05 volumio2 go-librespot[38312]: time="2025-06-20T00:42:05+02:00" level=info msg="zeroconf server listening on port 42881" Jun 20 00:42:05 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 20 00:42:05 volumio2 sudo[38322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:42:05 volumio2 sudo[38322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 20 00:42:05 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:42:05 volumio2 volumio[38100]: info: Asound.conf file unchanged, so no further update is needed Jun 20 00:42:05 volumio2 systemd[1]: shairport-sync.service: Consumed 1.678s CPU time. Jun 20 00:42:05 volumio2 volumio[38100]: info: Output device has changed, restarting MPD Jun 20 00:42:05 volumio2 sudo[38324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:42:05 volumio2 sudo[38324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 volumio[38100]: info: Output device has changed, restarting Shairport Sync Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:42:05 volumio2 sudo[38330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 20 00:42:05 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:42:05 volumio2 sudo[38330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 sudo[38333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 20 00:42:05 volumio2 sudo[38330]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 sudo[38320]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 sudo[38333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 volumio[38100]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 20 00:42:05 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 20 00:42:05 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 20 00:42:05 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:42:05 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:42:05 volumio2 sudo[38324]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 sudo[38322]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 volumio[38100]: info: MPD Permissions set Jun 20 00:42:05 volumio2 volumio[38100]: info: Shairport-Sync Started Jun 20 00:42:05 volumio2 volumio[38100]: Error adding Membership: Error: addMembership EINVAL Jun 20 00:42:05 volumio2 systemd[1]: mpd.service: Deactivated successfully. Jun 20 00:42:05 volumio2 volumio[38100]: info: Shairport-Sync Started Jun 20 00:42:05 volumio2 volumio[38100]: info: Shairport-Sync Started Jun 20 00:42:05 volumio2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 20 00:42:05 volumio2 systemd[1]: mpd.socket: Deactivated successfully. Jun 20 00:42:05 volumio2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 20 00:42:05 volumio2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 20 00:42:05 volumio2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 20 00:42:05 volumio2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 20 00:42:05 volumio2 volumio[38100]: info: CoreCommandRouter::volumioGetState Jun 20 00:42:05 volumio2 volumio[38100]: info: CorePlayQueue::getTrack 0 Jun 20 00:42:05 volumio2 volumio[38100]: info: Starting Shairport Sync Jun 20 00:42:05 volumio2 sudo[38366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 20 00:42:05 volumio2 sudo[38366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:05 volumio2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 20 00:42:05 volumio2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 20 00:42:05 volumio2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:42:05 volumio2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 20 00:42:05 volumio2 sudo[38364]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 20 00:42:05 volumio2 sudo[38364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 20 00:42:05 volumio2 sudo[38366]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 sudo[38364]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:05 volumio2 volumio[38100]: info: Shairport-Sync Started Jun 20 00:42:06 volumio2 volumio[38100]: verbose: New Socket.io Connection to 192.168.1.215 from 192.168.1.206 UA: Mozilla/5.0 (Linux; Android 15; SM-S921B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 20 00:42:06 volumio2 volumio[38100]: Searching all installed plugins Jun 20 00:42:06 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 20 00:42:06 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: , search Jun 20 00:42:06 volumio2 volumio[38100]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 20 00:42:06 volumio2 volumio[38100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 00:42:06 volumio2 volumio[38100]: TypeError: Cannot read properties of undefined (reading 'then') Jun 20 00:42:06 volumio2 volumio[38100]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1583:17) Jun 20 00:42:06 volumio2 volumio[38100]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jun 20 00:42:06 volumio2 volumio[38100]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Jun 20 00:42:06 volumio2 volumio[38100]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Jun 20 00:42:06 volumio2 volumio[38100]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Jun 20 00:42:06 volumio2 volumio[38100]: at Socket.emit (node:events:514:28) Jun 20 00:42:06 volumio2 volumio[38100]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 20 00:42:06 volumio2 volumio[38100]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jun 20 00:42:06 volumio2 volumio[38100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 20 00:42:06 volumio2 sudo[38400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-20 00:41' Jun 20 00:42:06 volumio2 sudo[38400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 20 00:42:06 volumio2 mpd[38370]: 2025-06-20T00:42:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 20 00:42:06 volumio2 systemd[1]: Started mpd.service - Music Player Daemon. Jun 20 00:42:06 volumio2 sudo[38258]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:06 volumio2 sudo[38248]: pam_unix(sudo:session): session closed for user root Jun 20 00:42:06 volumio2 sudo[38333]: pam_unix(sudo:session): session closed for user root PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 14 07:01:29 UTC 2025" VOLUMIO_VERSION="4.012" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="38c4f0e007cb9b6482e9f27bbe65b0b5"