-- Logs begin at Thu 2019-02-14 10:12:01 GMT, end at Sat 2025-06-21 17:11:12 BST. -- Jun 21 17:10:00 volumio-office volumio-time-update[553]: volumio-time-update-util: Setting system time to: 2025-06-21 17:10:26 Jun 21 17:10:00 volumio-office sudo[943]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-06-21 17:10:26 Jun 21 17:10:00 volumio-office sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:00 volumio-office volumio[835]: info: Loading plugin "network"... Jun 21 17:10:00 volumio-office volumio[835]: info: Refreshing Cached IP Addresses Jun 21 17:10:00 volumio-office dbus-daemon[559]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=944 comm="timedatectl set-time 2025-06-21 17:10:26 ") Jun 21 17:10:00 volumio-office sudo[946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 17:10:00 volumio-office sudo[946]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:00 volumio-office sudo[946]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:00 volumio-office systemd[1]: Starting Time & Date Service... Jun 21 17:10:00 volumio-office volumio[835]: info: Loading plugin "services"... Jun 21 17:10:00 volumio-office volumio[835]: info: Loading plugin "alsa_controller"... Jun 21 17:10:00 volumio-office sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 17:10:00 volumio-office sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:00 volumio-office sudo[948]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:00 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 17:10:00 volumio-office volumio[835]: info: Loading plugin "wizard"... Jun 21 17:10:00 volumio-office volumio[835]: info: Loading plugin "networkfs"... Jun 21 17:10:00 volumio-office dbus-daemon[559]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 21 17:10:00 volumio-office systemd[1]: Started Time & Date Service. Jun 21 17:10:26 volumio-office systemd-timedated[949]: Changed local time to Sat Jun 21 17:10:26 2025 Jun 21 17:10:26 volumio-office sudo[943]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:26 volumio-office volumio-time-update[553]: volumio-time-update-util: System time updated successfully. Jun 21 17:10:26 volumio-office systemd[1]: Started Volumio Time Update Utility. Jun 21 17:10:26 volumio-office systemd[1]: Reached target Multi-User System. Jun 21 17:10:26 volumio-office systemd[1]: Reached target Graphical Interface. Jun 21 17:10:26 volumio-office volumio[835]: info: Starting Udev Watcher for removable devices Jun 21 17:10:26 volumio-office volumio[835]: info: Ignoring mount for partition: boot Jun 21 17:10:26 volumio-office volumio[835]: info: Ignoring mount for partition: volumio Jun 21 17:10:26 volumio-office volumio[835]: info: Ignoring mount for partition: volumio_data Jun 21 17:10:26 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 21 17:10:26 volumio-office volumio[835]: info: Loading plugin "volumio_command_line_client"... Jun 21 17:10:26 volumio-office volumio[835]: info: Loading plugin "upnp"... Jun 21 17:10:26 volumio-office volumio[835]: info: [1750522226072] Starting Upmpd Daemon Jun 21 17:10:26 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 21 17:10:26 volumio-office volumio[835]: info: Loading plugin "my_music"... Jun 21 17:10:26 volumio-office systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 21 17:10:26 volumio-office volumio[835]: info: Loading plugin "mpd"... Jun 21 17:10:26 volumio-office systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 21 17:10:26 volumio-office systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 21 17:10:26 volumio-office systemd[1]: Startup finished in 16.193s (kernel) + 19.314s (userspace) = 35.507s. Jun 21 17:10:26 volumio-office volumio[835]: info: Loading plugin "upnp_browser"... Jun 21 17:10:27 volumio-office volumio[835]: info: Loading plugin "alarm-clock"... Jun 21 17:10:27 volumio-office volumio[835]: info: Loading plugin "airplay_emulation"... Jun 21 17:10:27 volumio-office volumio[835]: info: Starting Shairport Sync Jun 21 17:10:27 volumio-office volumio[835]: info: Loading plugin "last_100"... Jun 21 17:10:27 volumio-office volumio[835]: info: Loading plugin "webradio"... Jun 21 17:10:27 volumio-office volumio[835]: info: Loading plugin "i2s_dacs"... Jun 21 17:10:27 volumio-office volumio[835]: info: I2S DAC not set, start Auto-detection Jun 21 17:10:27 volumio-office volumio[835]: info: Loading plugin "volumiodiscovery"... Jun 21 17:10:27 volumio-office volumio[835]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 21 17:10:27 volumio-office volumio[835]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 17:10:27 volumio-office volumio[835]: *** WARNING *** For more information see Jun 21 17:10:27 volumio-office volumio[835]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 21 17:10:27 volumio-office node[835]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 21 17:10:27 volumio-office volumio[835]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 17:10:27 volumio-office volumio[835]: *** WARNING *** For more information see Jun 21 17:10:27 volumio-office node[835]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 17:10:27 volumio-office node[835]: *** WARNING *** For more information see Jun 21 17:10:27 volumio-office node[835]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 21 17:10:27 volumio-office node[835]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 17:10:27 volumio-office node[835]: *** WARNING *** For more information see Jun 21 17:10:27 volumio-office volumio[835]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 21 17:10:27 volumio-office volumio[835]: info: Discovery: Started advertising with name: Volumio Office Jun 21 17:10:27 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 21 17:10:27 volumio-office volumio[835]: info: Loading plugin "spop"... Jun 21 17:10:28 volumio-office volumio-remote-updater[575]: [2025-06-21 17:10:28] [connect] Successful connection Jun 21 17:10:29 volumio-office volumio[835]: info: Loading plugin "outputs"... Jun 21 17:10:29 volumio-office volumio[835]: info: Loading plugin "albumart"... Jun 21 17:10:29 volumio-office volumio[835]: info: Plugin example_plugin is not enabled Jun 21 17:10:29 volumio-office volumio[835]: info: Loading plugin "inputs"... Jun 21 17:10:29 volumio-office volumio[835]: info: Loading plugin "updater_comm"... Jun 21 17:10:29 volumio-office volumio[835]: info: Plugin mpdemulation is not enabled Jun 21 17:10:29 volumio-office volumio[835]: info: Loading plugin "rest_api"... Jun 21 17:10:29 volumio-office volumio[835]: info: Loading plugin "websocket"... Jun 21 17:10:29 volumio-office volumio[835]: info: Starting Socket.io Server version 2.3.0 Jun 21 17:10:29 volumio-office volumio[835]: info: Loading i18n strings for locale en Jun 21 17:10:29 volumio-office volumio[835]: Updating browse sources language Jun 21 17:10:29 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 17:10:29 volumio-office volumio[835]: Forking 3 albumart workers Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::initPlayerControls Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: Express server listening on port 3000 Jun 21 17:10:30 volumio-office volumio[835]: [Metrics] WebUI: 8s 668.62ms Jun 21 17:10:30 volumio-office volumio[835]: info: CoreStateMachine::resetVolumioState Jun 21 17:10:30 volumio-office volumio[835]: info: CoreStateMachine::getcurrentVolume Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::volumioRetrievevolume Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:30 volumio-office volumio[835]: info: Volumio Network Manager: Network status updated: 1 Jun 21 17:10:30 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61:3000 from 192.168.68.79 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 21 17:10:30 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6 Pro Build/BP2A.250605.031.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 21 17:10:30 volumio-office volumio-remote-updater[575]: [2025-06-21 17:10:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750522228 101 Jun 21 17:10:30 volumio-office volumio[835]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Jun 21 17:10:30 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61:3000 from 192.168.68.101 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jun 21 17:10:30 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61:3000 from 192.168.68.62 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 21 17:10:30 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61:3000 from 192.168.68.63 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 21 17:10:30 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61:3000 from 192.168.68.79 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 21 17:10:30 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6 Pro Build/BP2A.250605.031.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: 8 Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:30 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:30 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 17:10:30 volumio-office volumio[835]: info: Discovery: Getting this device information Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:30 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 21 17:10:30 volumio-office volumio[835]: Starting albumart workers Jun 21 17:10:30 volumio-office volumio[835]: info: VolumeController:: Volume=33 Mute =false Jun 21 17:10:30 volumio-office volumio[835]: info: CoreStateMachine::pushState Jun 21 17:10:30 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 17:10:30 volumio-office volumio[835]: Starting albumart workers Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::volumioPushState Jun 21 17:10:30 volumio-office volumio[835]: info: CoreStateMachine::updateTrackBlock Jun 21 17:10:30 volumio-office volumio[835]: info: CorePlayQueue::getTrackBlock Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::volumioRetrievevolume Jun 21 17:10:30 volumio-office volumio[835]: info: Setting Device type: Raspberry PI Jun 21 17:10:30 volumio-office volumio[835]: Starting albumart workers Jun 21 17:10:30 volumio-office volumio[835]: info: CoreStateMachine::setRepeat null single undefined Jun 21 17:10:30 volumio-office volumio[835]: info: CoreStateMachine::pushState Jun 21 17:10:30 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::volumioPushState Jun 21 17:10:30 volumio-office volumio[835]: info: CoreStateMachine::setRandom null Jun 21 17:10:30 volumio-office volumio[835]: info: CoreStateMachine::pushState Jun 21 17:10:30 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:30 volumio-office volumio[835]: info: CoreCommandRouter::volumioPushState Jun 21 17:10:30 volumio-office volumio[835]: info: Listing playlists Jun 21 17:10:30 volumio-office volumio[835]: info: Listing playlists Jun 21 17:10:30 volumio-office volumio[835]: info: Listing playlists Jun 21 17:10:30 volumio-office volumio[835]: info: Listing playlists Jun 21 17:10:31 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61:3000 from 192.168.68.101 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:31 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:31 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:31 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:31 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: adding c46ae0a6-9a75-4458-9fa5-f8ee92ab12b3 Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: Found device Volumio Office Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:31 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: adding 49fb1596-08c2-4547-ade2-46a45e00d8c6 Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: Found device Volumio Kitchen Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: Connecting to remote: 192.168.68.63 Jun 21 17:10:31 volumio-office volumio[835]: info: VolumeController:: Volume=33 Mute =false Jun 21 17:10:31 volumio-office volumio[835]: info: CoreStateMachine::pushState Jun 21 17:10:31 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::volumioPushState Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: adding 5290c372-2681-4d8b-a65b-2a7ecbeef864 Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: Found device Volumio Livingroom Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: Connecting to remote: 192.168.68.62 Jun 21 17:10:31 volumio-office volumio[835]: info: Completed loading Core Plugins Jun 21 17:10:31 volumio-office volumio[835]: info: Preparing to generate the ALSA configuration file Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: Connected to remote: 192.168.68.63 Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: adding 4937f969-251a-45ba-aced-484620e63bf8 Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: Found device Volumio Bedroom Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: Connecting to remote: 192.168.68.79 Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: Connected to remote: 192.168.68.62 Jun 21 17:10:31 volumio-office volumio[835]: info: Discovery: Connected to remote: 192.168.68.79 Jun 21 17:10:31 volumio-office volumio[835]: info: Asound.conf file written Jun 21 17:10:31 volumio-office sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 21 17:10:31 volumio-office sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:31 volumio-office sudo[1043]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:31 volumio-office volumio[835]: info: Output device has changed, restarting MPD Jun 21 17:10:31 volumio-office volumio[835]: info: Output device has changed, restarting Shairport Sync Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:31 volumio-office sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:31 volumio-office sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:31 volumio-office sudo[1048]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:31 volumio-office sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 21 17:10:31 volumio-office sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:31 volumio-office systemd[1]: Stopping Music Player Daemon... Jun 21 17:10:31 volumio-office volumio[835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 21 17:10:31 volumio-office volumio[835]: info: ___________ START PLUGINS ___________ Jun 21 17:10:31 volumio-office volumio[835]: info: ControllerMpd::onStart: Initializing MPD Jun 21 17:10:31 volumio-office volumio[835]: info: Creating MPD Configuration file Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 17:10:31 volumio-office volumio[835]: info: [1750522231990] CoreMusicLibrary::Adding element Media Servers Jun 21 17:10:31 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 17:10:31 volumio-office sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 21 17:10:31 volumio-office sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:32 volumio-office sudo[1057]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:32 volumio-office sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 21 17:10:32 volumio-office sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:32 volumio-office systemd[1]: mpd.service: Succeeded. Jun 21 17:10:32 volumio-office systemd[1]: Stopped Music Player Daemon. Jun 21 17:10:32 volumio-office volumio[835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 17:10:32 volumio-office volumio[835]: info: [1750522232162] CoreMusicLibrary::Adding element Last_100 Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 17:10:32 volumio-office volumio[835]: info: [1750522232166] CoreMusicLibrary::Adding element Webradio Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 17:10:32 volumio-office systemd[1]: Starting Music Player Daemon... Jun 21 17:10:32 volumio-office volumio[835]: info: Initializing BBC Radios Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: Creating Spotify config file Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office sudo[1065]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 21 17:10:32 volumio-office sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:32 volumio-office sudo[1065]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:32 volumio-office volumio[835]: info: Volumio Calling Home Jun 21 17:10:32 volumio-office volumio[835]: info: MPD Permissions set Jun 21 17:10:32 volumio-office volumio[835]: info: MPD Permissions set Jun 21 17:10:32 volumio-office volumio[835]: info: Spotify config file written Jun 21 17:10:32 volumio-office volumio[835]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 21 17:10:32 volumio-office volumio[835]: info: Volumio called home Jun 21 17:10:32 volumio-office sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 21 17:10:32 volumio-office volumio[835]: info: No need to fix Spotify hosts Jun 21 17:10:32 volumio-office sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:32 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:32 volumio-office systemd[1]: Started go-librespot Daemon. Jun 21 17:10:32 volumio-office go-librespot[1091]: Librespot-go daemon starting... Jun 21 17:10:32 volumio-office sudo[1085]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:32 volumio-office volumio[835]: info: Starting Shairport Sync Jun 21 17:10:32 volumio-office volumio[835]: info: Starting Shairport Sync Jun 21 17:10:32 volumio-office sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 21 17:10:32 volumio-office sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:32 volumio-office volumio[835]: info: Starting Shairport Sync Jun 21 17:10:32 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 21 17:10:32 volumio-office volumio[835]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 21 17:10:32 volumio-office volumio[835]: SPOTIFY: BQDhLdcGQj0LUXIopfGRHgSU_gw7JWD6AlKT7JMiVYglv6yKLOAl0xpibVeTVYHLK15jUEkuoYjosWaZvy7DOFXdCXZygvzoKEv8sliq2yCmZfbmF_dHv_o56Jsd9tNzoopBk-3JCWA13w6JI02h44uzYjI5piuqIvexVXb_31Sg49NLRZcJr4PZnkNnCJUWWjouQHUEY00xjmfKfUe6spMS0l4RMPnlH66XKvSqBS11giP3YrMhmRfrKyQ5NO6RDAZFTOL2WUGj956g5sPHN99McA Jun 21 17:10:32 volumio-office volumio[835]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jun 21 17:10:32 volumio-office sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 21 17:10:32 volumio-office sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:32 volumio-office systemd[1]: shairport-sync.service: Succeeded. Jun 21 17:10:32 volumio-office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 21 17:10:32 volumio-office volumio[835]: info: New Spotify access token = BQDhLdcGQj0LUXIopfGRHgSU_gw7JWD6AlKT7JMiVYglv6yKLOAl0xpibVeTVYHLK15jUEkuoYjosWaZvy7DOFXdCXZygvzoKEv8sliq2yCmZfbmF_dHv_o56Jsd9tNzoopBk-3JCWA13w6JI02h44uzYjI5piuqIvexVXb_31Sg49NLRZcJr4PZnkNnCJUWWjouQHUEY00xjmfKfUe6spMS0l4RMPnlH66XKvSqBS11giP3YrMhmRfrKyQ5NO6RDAZFTOL2WUGj956g5sPHN99McA Jun 21 17:10:32 volumio-office volumio[835]: info: Spotify credentials grant success - running version from March 24, 2019 Jun 21 17:10:32 volumio-office sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 21 17:10:32 volumio-office sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:33 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:33 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:33 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 21 17:10:33 volumio-office sudo[1102]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:33 volumio-office volumio[835]: info: Shairport-Sync Started Jun 21 17:10:33 volumio-office volumio[835]: Error adding Membership: Error: addMembership EINVAL Jun 21 17:10:33 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 21 17:10:33 volumio-office systemd[1]: shairport-sync.service: Succeeded. Jun 21 17:10:33 volumio-office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 21 17:10:33 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 21 17:10:33 volumio-office sudo[1104]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:33 volumio-office sudo[1108]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:33 volumio-office volumio[835]: info: Shairport-Sync Started Jun 21 17:10:33 volumio-office volumio[835]: info: Shairport-Sync Started Jun 21 17:10:33 volumio-office volumio[835]: SPOTIFY: User informations: {"country":"GB","display_name":"T","email":"t_taiwo@live.co.uk","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/119023773"},"followers":{"href":null,"total":33},"href":"https://api.spotify.com/v1/users/119023773","id":"119023773","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee859dcf1a90f11c9646f1f9de7a","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b829dcf1a90f11c9646f1f9de7a","width":64}],"product":"premium","type":"user","uri":"spotify:user:119023773"} Jun 21 17:10:33 volumio-office volumio[835]: info: Spotify Successfully logged in Jun 21 17:10:33 volumio-office volumio[835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 17:10:33 volumio-office volumio[835]: info: [1750522233168] CoreMusicLibrary::Adding element Spotify Jun 21 17:10:33 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 17:10:33 volumio-office volumio[835]: Cannot find translation for source Spotify Jun 21 17:10:33 volumio-office go-librespot[1091]: time="2025-06-21T17:10:33+01:00" level=info msg="generated new device id: e8a4e23519d3efb2fb01f66e1126078e7fbe6f13" Jun 21 17:10:33 volumio-office go-librespot[1091]: time="2025-06-21T17:10:33+01:00" level=debug msg="stored credentials found for 119023773" Jun 21 17:10:33 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:33 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:33 volumio-office go-librespot[1091]: time="2025-06-21T17:10:33+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 21 17:10:33 volumio-office go-librespot[1091]: time="2025-06-21T17:10:33+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 21 17:10:33 volumio-office go-librespot[1091]: time="2025-06-21T17:10:33+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 21 17:10:33 volumio-office go-librespot[1091]: time="2025-06-21T17:10:33+01:00" level=debug msg="zeroconf server listening on port 46177" Jun 21 17:10:33 volumio-office go-librespot[1091]: time="2025-06-21T17:10:33+01:00" level=debug msg="obtained new client token: AAA6eFYKSDl5kHMe9B2N+2kyFCJVMa5s+Ckh7I6WHOMCUgMO35pEqbl6bqoIZgv5yiNrdehXoo+p18os/O9GiUA0dwzYxIsPRvPTZF1nlhUNBqUBKEXDERrE/y2Mg1MAwzbW36DHGWBhP2uUXtHqephBw5hu9KIzxxPM98kDgFk0fJigjWz8CnsgLk6b+Qs/E2aTm6TtVXjwAVYTqsV8harMSO3Pfb68xYsmdFTBWzteIi6x4YZhSzpjYQ==" Jun 21 17:10:33 volumio-office go-librespot[1091]: time="2025-06-21T17:10:33+01:00" level=info msg="connected to ap-gew1.spotify.com:4070" Jun 21 17:10:33 volumio-office go-librespot[1091]: time="2025-06-21T17:10:33+01:00" level=debug msg="completed keyexchange" Jun 21 17:10:33 volumio-office mpd[1080]: Jun 21 17:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 21 17:10:33 volumio-office systemd[1]: Started Music Player Daemon. Jun 21 17:10:33 volumio-office sudo[1059]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:33 volumio-office sudo[1050]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:34 volumio-office volumio[835]: info: Completed starting Core Plugins Jun 21 17:10:34 volumio-office volumio[835]: info: ------------------------------------------- Jun 21 17:10:34 volumio-office volumio[835]: info: ----- MyVolumio plugins startup ---- Jun 21 17:10:34 volumio-office volumio[835]: info: ------------------------------------------- Jun 21 17:10:34 volumio-office volumio[835]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 21 17:10:34 volumio-office volumio[835]: error: MPD error: The expression evaluated to a falsy value: Jun 21 17:10:34 volumio-office volumio[835]: assert.ok(self.idling) Jun 21 17:10:34 volumio-office volumio[835]: error: The expression evaluated to a falsy value: Jun 21 17:10:34 volumio-office volumio[835]: assert.ok(self.idling) Jun 21 17:10:34 volumio-office volumio[835]: error: updateQueue error: null Jun 21 17:10:34 volumio-office volumio[835]: info: MPD running with PID1080 Jun 21 17:10:34 volumio-office volumio[835]: ,establishing connection Jun 21 17:10:34 volumio-office volumio[835]: error: updateQueue error: null Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="completed challenge" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="authenticated as 119023773" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="authenticated as 119023773" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="dealer connection opened" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="initializing zeroconf session, username: 119023773" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="autoplay enabled: false" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="received connection id: ODI0NjNjOGItYTQ2Yy00ZDU2LWJhMTAtZmY3YjBlZjc2ODI1K2RlYWxlcit0Y3A6Ly8wYWNhNDBlZi5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArOURBQTVDMjA4QkRFMDg4QUIzQjVBRTgxNTYyRUVFQTM5RkQxMjU0MDA1MEZGNjRCMzFCMTk2RTI3RTlFQkZBRg==" Jun 21 17:10:34 volumio-office go-librespot[1091]: time="2025-06-21T17:10:34+01:00" level=debug msg="put connect state because NEW_DEVICE" Jun 21 17:10:35 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6 Pro Build/BP2A.250605.031.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 21 17:10:35 volumio-office volumio[835]: info: go-librespot daemon successfully initialized Jun 21 17:10:36 volumio-office sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 17:10:36 volumio-office sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:36 volumio-office sudo[1138]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:36 volumio-office sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 17:10:36 volumio-office sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:36 volumio-office sudo[1140]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:36 volumio-office sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 21 17:10:36 volumio-office sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:36 volumio-office systemd[1]: Started UPnP Renderer front-end to MPD. Jun 21 17:10:36 volumio-office sudo[1144]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:36 volumio-office volumio[835]: info: Upmpdcli Daemon Started Jun 21 17:10:36 volumio-office volumio[1146]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 21 17:10:38 volumio-office volumio[835]: info: Initializing connection to go-librespot Websocket Jun 21 17:10:38 volumio-office go-librespot[1091]: time="2025-06-21T17:10:38+01:00" level=debug msg="new websocket client" Jun 21 17:10:38 volumio-office volumio[835]: info: Connection to go-librespot Websocket established Jun 21 17:10:39 volumio-office volumio[835]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:40 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:40 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6 Pro Build/BP2A.250605.031.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 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 17:10:40 volumio-office volumio[835]: info: Received Get System Info Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 17:10:40 volumio-office volumio[835]: info: Discovery: Getting this device information Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:40 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetVisibleSources Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:40 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 17:10:40 volumio-office volumio[835]: info: Received Get System Info Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 17:10:40 volumio-office volumio[835]: info: Discovery: Getting this device information Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:40 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:40 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:40 volumio-office volumio[835]: info: Listing playlists Jun 21 17:10:40 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetQueue Jun 21 17:10:40 volumio-office volumio[835]: info: CoreStateMachine::getQueue Jun 21 17:10:40 volumio-office volumio[835]: info: CorePlayQueue::getQueue Jun 21 17:10:41 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 21 17:10:41 volumio-office volumio[835]: info: Getting Spotify volume Jun 21 17:10:41 volumio-office volumio[835]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jun 21 17:10:41 volumio-office volumio[835]: info: Spotify volume: 100 Jun 21 17:10:41 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:41 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:41 volumio-office volumio[835]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Jun 21 17:10:41 volumio-office volumio[835]: SPOTIFY: SPOTIFY VOLUME 100 Jun 21 17:10:41 volumio-office volumio[835]: SPOTIFY: VOLUMIO VOLUME 33 Jun 21 17:10:41 volumio-office volumio[835]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 21 17:10:41 volumio-office volumio[835]: info: Setting Spotify Volume from Volumio: 33 Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 21 17:10:42 volumio-office volumio[835]: info: Adding plugin bluetooth to MyMusic Plugins Jun 21 17:10:42 volumio-office volumio[835]: info: Adding plugin multiroom to MyMusic Plugins Jun 21 17:10:42 volumio-office volumio[835]: info: Adding plugin metavolumio to MyMusic Plugins Jun 21 17:10:42 volumio-office volumio[835]: info: Adding plugin cd_controller to MyMusic Plugins Jun 21 17:10:42 volumio-office volumio[835]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 21 17:10:42 volumio-office volumio[835]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 21 17:10:42 volumio-office volumio[835]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 21 17:10:42 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 21 17:10:43 volumio-office systemd[1]: systemd-fsckd.service: Succeeded. Jun 21 17:10:43 volumio-office volumio[835]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 21 17:10:43 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 21 17:10:43 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:43 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:43 volumio-office volumio[835]: info: Starting MyVolumio Remote Streaming Endpoints Jun 21 17:10:43 volumio-office volumio[835]: info: MyVolumio login type: Token Jun 21 17:10:43 volumio-office volumio[835]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 21 17:10:43 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 21 17:10:45 volumio-office volumio[835]: info: Starting Streaming Service Transparent Proxy Jun 21 17:10:45 volumio-office volumio[835]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 21 17:10:45 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 21 17:10:45 volumio-office volumio[835]: info: Streaming services startup Jun 21 17:10:45 volumio-office volumio[835]: info: Starting Streaming Daemon Jun 21 17:10:45 volumio-office sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 21 17:10:45 volumio-office sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:45 volumio-office sudo[1172]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:45 volumio-office volumio[835]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 21 17:10:45 volumio-office volumio[835]: SPOTIFY: SETTING SPOTIFY VOLUME 33 Jun 21 17:10:45 volumio-office volumio[835]: info: Sending Spotify command with payload to local API: /player/volume Jun 21 17:10:45 volumio-office go-librespot[1091]: time="2025-06-21T17:10:45+01:00" level=debug msg="update volume to 21626/65535" Jun 21 17:10:45 volumio-office volumio[835]: error: Cannot start Volumio Streaming Daemon Jun 21 17:10:45 volumio-office volumio[835]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 21 17:10:45 volumio-office volumio[835]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 21 17:10:45 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:45 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:45 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61:3000 from 192.168.68.101 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Jun 21 17:10:45 volumio-office go-librespot[1091]: time="2025-06-21T17:10:45+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 21 17:10:45 volumio-office go-librespot[1091]: time="2025-06-21T17:10:45+01:00" level=trace msg="emitting websocket event: volume" Jun 21 17:10:45 volumio-office volumio[835]: SPOTIFY: received: {"type":"volume","data":{"value":33,"max":100}} Jun 21 17:10:45 volumio-office volumio[835]: SPOTIFY: RECEIVED SPOTIFY VOLUME 33 Jun 21 17:10:45 volumio-office volumio[835]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 21 17:10:46 volumio-office volumio[835]: info: MyVolumio token set successfully Jun 21 17:10:46 volumio-office volumio[835]: info: MYVOLUMIO: Adding device Jun 21 17:10:46 volumio-office volumio[835]: info: MYVOLUMIO: Evaluating Server Jun 21 17:10:46 volumio-office volumio[835]: STREAMING PROXY: Starting server on port 3245 Jun 21 17:10:46 volumio-office volumio[835]: Node JS runtime: 14 Jun 21 17:10:46 volumio-office volumio[835]: info: MyVolumio Plan changed: premium Jun 21 17:10:46 volumio-office volumio[835]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 21 17:10:46 volumio-office volumio[835]: info: Removing browser output: myVolumio user plan is not superstar Jun 21 17:10:46 volumio-office volumio[835]: info: Removing audio output: Jun 21 17:10:46 volumio-office volumio[835]: info: MYVOLUMIO: Adding device Jun 21 17:10:46 volumio-office volumio[835]: info: MYVOLUMIO: Evaluating Server Jun 21 17:10:46 volumio-office volumio[835]: info: Remote config written successfully Jun 21 17:10:46 volumio-office volumio[835]: info: Starting Tunnel 1 Jun 21 17:10:46 volumio-office volumio[835]: info: Starting Tunnel Connection Checker Jun 21 17:10:46 volumio-office volumio[835]: info: Completed starting MyVolumio Plugin Jun 21 17:10:47 volumio-office volumio[835]: info: MYVolumio Device enabled Jun 21 17:10:47 volumio-office volumio[835]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 21 17:10:47 volumio-office volumio[835]: info: MyVolumio status changed Jun 21 17:10:47 volumio-office volumio[835]: info: Streaming services startup Jun 21 17:10:47 volumio-office volumio[835]: info: Starting Streaming Daemon Jun 21 17:10:47 volumio-office sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 21 17:10:47 volumio-office sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:47 volumio-office volumio[835]: info: Setting Geolocation for MyVolumio to eu3 Jun 21 17:10:47 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:47 volumio-office sudo[1226]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:47 volumio-office volumio[835]: error: Cannot start Volumio Streaming Daemon Jun 21 17:10:47 volumio-office volumio[835]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 21 17:10:47 volumio-office volumio[835]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 21 17:10:47 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 21 17:10:47 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 21 17:10:47 volumio-office systemd[1]: systemd-hostnamed.service: Succeeded. Jun 21 17:10:47 volumio-office volumio[835]: info: [1750522247488] Starting BluetoothController Jun 21 17:10:47 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 21 17:10:49 volumio-office sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 21 17:10:49 volumio-office sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:49 volumio-office sudo[1233]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:49 volumio-office volumio[835]: info: MRS: MultiRoom plugin initialized Jun 21 17:10:49 volumio-office volumio[835]: info: MRS: STOPPING SNAPCLIENT Jun 21 17:10:49 volumio-office volumio[835]: info: MRS: Snap server stop Jun 21 17:10:49 volumio-office volumio[835]: info: MRS: STOPPING volumioStreaming Jun 21 17:10:49 volumio-office sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 21 17:10:49 volumio-office sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:49 volumio-office sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 21 17:10:49 volumio-office sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:49 volumio-office sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 21 17:10:49 volumio-office sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 21 17:10:49 volumio-office sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 21 17:10:49 volumio-office sudo[1249]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:49 volumio-office sudo[1251]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:49 volumio-office sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:49 volumio-office sudo[1257]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 21 17:10:49 volumio-office sudo[1254]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 21 17:10:49 volumio-office volumio[835]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jun 21 17:10:49 volumio-office volumio[835]: info: Preparing to generate the ALSA configuration file Jun 21 17:10:50 volumio-office volumio[835]: info: Updating MyVolumio device info Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:50 volumio-office volumio[835]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 21 17:10:50 volumio-office volumio[835]: info: Reading ALSA contributions from plugins. Jun 21 17:10:50 volumio-office volumio[835]: info: Setting Geolocation for MyVolumio to eu3 Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Removed streaming files Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: volumioStreaming STOPPED Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: SNAPSERVER STOPPED Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: SNAPCLIENT STOPPED Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 17:10:50 volumio-office volumio[835]: info: Discovery: Getting this device information Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:50 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 17:10:50 volumio-office volumio[835]: info: Discovery: Getting this device information Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:50 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 17:10:50 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61:3000 from 192.168.68.101 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:50 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 21 17:10:50 volumio-office volumio[835]: info: Asound.conf file written Jun 21 17:10:50 volumio-office sudo[1261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 21 17:10:50 volumio-office sudo[1261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:50 volumio-office sudo[1261]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:50 volumio-office volumio[835]: info: Output device has changed, restarting MPD Jun 21 17:10:50 volumio-office volumio[835]: info: Output device has changed, restarting Shairport Sync Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:50 volumio-office sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 21 17:10:50 volumio-office sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:50 volumio-office sudo[1266]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:50 volumio-office sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 21 17:10:50 volumio-office sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:50 volumio-office systemd[1]: Stopping Music Player Daemon... Jun 21 17:10:50 volumio-office volumio[835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 21 17:10:50 volumio-office systemd[1]: mpd.service: Succeeded. Jun 21 17:10:50 volumio-office systemd[1]: Stopped Music Player Daemon. Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Pushing multiroomSync output for this device Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Pushing multiroomSync output Jun 21 17:10:50 volumio-office systemd[1]: Starting Music Player Daemon... Jun 21 17:10:50 volumio-office sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Office Jun 21 17:10:50 volumio-office sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:50 volumio-office volumio[835]: info: Adding audio output: Jun 21 17:10:50 volumio-office volumio[835]: info: Adding audio output: Jun 21 17:10:50 volumio-office sudo[1278]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jun 21 17:10:50 volumio-office volumio[835]: info: Adding METAVOLUMIO REST API Endpoints Jun 21 17:10:50 volumio-office volumio[835]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 21 17:10:50 volumio-office volumio[835]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 21 17:10:50 volumio-office volumio[835]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 21 17:10:50 volumio-office volumio[835]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jun 21 17:10:50 volumio-office volumio[835]: info: Adding inputs REST Endpoints Jun 21 17:10:50 volumio-office volumio[835]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jun 21 17:10:50 volumio-office volumio[835]: info: Scanning Audio Inputs Jun 21 17:10:50 volumio-office sudo[1274]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 21 17:10:50 volumio-office sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:50 volumio-office volumio[835]: info: Checking against Known Cards name Jun 21 17:10:50 volumio-office sudo[1274]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 17:10:50 volumio-office volumio[835]: info: [1750522250602] CoreMusicLibrary::Adding element USB Audio Device Jun 21 17:10:50 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 17:10:50 volumio-office volumio[835]: Cannot find translation for source Spotify Jun 21 17:10:50 volumio-office volumio[835]: Cannot find translation for source USB Audio Device Jun 21 17:10:50 volumio-office volumio[835]: info: Checking against Known Cards name Jun 21 17:10:50 volumio-office volumio[835]: info: Adding Server instance for streaming Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jun 21 17:10:50 volumio-office volumio[835]: error: Hi Res Audio Failed Login: Missing Login Data Jun 21 17:10:50 volumio-office volumio[835]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 21 17:10:50 volumio-office volumio[835]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Jun 21 17:10:50 volumio-office volumio[835]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jun 21 17:10:50 volumio-office volumio[835]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jun 21 17:10:50 volumio-office volumio[835]: info: QobuzConnect: Starting Qobuz Connect socket and service Jun 21 17:10:50 volumio-office sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 21 17:10:50 volumio-office sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:50 volumio-office volumio[835]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jun 21 17:10:50 volumio-office volumio[835]: info: Stopping AccessToken refresher cron Jun 21 17:10:50 volumio-office sudo[1291]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:50 volumio-office sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 21 17:10:50 volumio-office sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:50 volumio-office volumio[835]: info: AccessToken refresher cron started Jun 21 17:10:50 volumio-office volumio[835]: info: Stopping AccessToken refresher cron for QOBUZ Jun 21 17:10:50 volumio-office volumio[835]: info: AccessToken refresher cron started for QOBUZ Jun 21 17:10:50 volumio-office volumio[835]: info: Adding TIDAL REST API Endpoints Jun 21 17:10:50 volumio-office volumio[835]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jun 21 17:10:50 volumio-office volumio[835]: info: Adding QOBUZ REST API Endpoints Jun 21 17:10:50 volumio-office volumio[835]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jun 21 17:10:50 volumio-office volumio[835]: info: Successfully Added MyVolumio device Jun 21 17:10:50 volumio-office systemd[1]: Started Volumio Qobuz Connect Service. Jun 21 17:10:50 volumio-office sudo[1293]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Adding multiroomSync output Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: This device was ungracefully shutdown, cleaning up Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Removing another device as multiroomSync client Jun 21 17:10:50 volumio-office volumio[835]: info: Adding audio output: Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Pushing multiroomSync output Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Adding multiroomSync output Jun 21 17:10:50 volumio-office volumio[835]: info: Adding audio output: Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Pushing multiroomSync output Jun 21 17:10:50 volumio-office volumio[835]: info: MRS: Adding multiroomSync output Jun 21 17:10:50 volumio-office volumio[835]: info: Adding audio output: Jun 21 17:10:51 volumio-office volumio[835]: info: MRS: Pushing multiroomSync output Jun 21 17:10:51 volumio-office volumio[835]: info: Successfully Added MyVolumio device Jun 21 17:10:51 volumio-office volumio[835]: info: Bluetooth name changed to Volumio Office Jun 21 17:10:51 volumio-office volumio[835]: info: MPD Permissions set Jun 21 17:10:51 volumio-office volumio[835]: info: MRS: Updating multiroomSync output Jun 21 17:10:51 volumio-office volumio[835]: info: MRS: Updating multiroomSync output Jun 21 17:10:51 volumio-office volumio[835]: info: Successfully Updated MyVolumio device Jun 21 17:10:51 volumio-office volumio[835]: info: Updating MyVolumio device info Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:51 volumio-office volumio[835]: info: Executing endpoint qc_getconfig Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 21 17:10:51 volumio-office qobuz-connect[1295]: 20250621 17:10:51.242 [1295.1295] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:51 volumio-office volumio[835]: [Metrics] CommandRouter: 28s 863.41ms Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::volumiosetStartupVolume Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:10:51 volumio-office volumio[835]: info: VolumeController:: Setting startup Volume 35 Jun 21 17:10:51 volumio-office volumio[835]: info: VolumeController::SetAlsaVolume35 Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::Close All Modals sent Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::Close All Modals sent Jun 21 17:10:51 volumio-office volumio[835]: info: CoreStateMachine::pushState Jun 21 17:10:51 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::volumioPushState Jun 21 17:10:51 volumio-office volumio[1146]: .............................................................................................................++++ Jun 21 17:10:51 volumio-office volumio[835]: info: MRS: Pushing multiroomSync output update for this device Jun 21 17:10:51 volumio-office qobuz-connect[1295]: 20250621 17:10:51.382 [1295.1295] INFO VolumeManager: [0x9dfc80]: Setting new playback volume: 75 Jun 21 17:10:51 volumio-office qobuz-connect[1295]: 20250621 17:10:51.382 [1295.1295] INFO VolumeManager: [0x9dfc80]: Setting new mute state: 0 Jun 21 17:10:51 volumio-office qobuz-connect[1295]: 20250621 17:10:51.382 [1295.1295] INFO QobuzConnect: [0x9e0650]: Client initialized! Jun 21 17:10:51 volumio-office qobuz-connect[1295]: 20250621 17:10:51.382 [1295.1295] INFO SampleApp: Starting Avahi advertising, name: Volumio Office, service name: _qobuz-connect._tcp Jun 21 17:10:51 volumio-office volumio[835]: info: MRS: Pushing multiroomSync output Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:51 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:51 volumio-office qobuz-connect[1295]: 20250621 17:10:51.398 [1295.1295] INFO LocalConfigManager: [0x9df560]: Starting Local Configuration server Jun 21 17:10:51 volumio-office qobuz-connect[1295]: 20250621 17:10:51.398 [1295.1295] INFO SampleApp: Starting Local configuration server Jun 21 17:10:51 volumio-office qobuz-connect[1295]: 20250621 17:10:51.399 [1295.1295] INFO SampleApp: Connected to UNIX socket client 0x9d4f60 Jun 21 17:10:51 volumio-office qobuz-connect[1295]: 20250621 17:10:51.399 [1295.1295] INFO SampleApp: Playback volume changed: 75 Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:51 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:51 volumio-office volumio[835]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 21 17:10:51 volumio-office volumio[835]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 21 17:10:51 volumio-office volumio[835]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 21 17:10:51 volumio-office volumio[835]: SPOTIFY: SPOTIFY VOLUME 33 Jun 21 17:10:51 volumio-office volumio[835]: SPOTIFY: VOLUMIO VOLUME 35 Jun 21 17:10:51 volumio-office volumio[835]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 21 17:10:51 volumio-office volumio[835]: info: Setting Spotify Volume from Volumio: 35 Jun 21 17:10:51 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:51 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:51 volumio-office volumio[835]: info: Listing playlists Jun 21 17:10:51 volumio-office volumio[835]: info: Listing playlists Jun 21 17:10:51 volumio-office volumio[835]: info: Starting Shairport Sync Jun 21 17:10:51 volumio-office volumio[835]: info: MRS: Found cast device: AS-AFTSSS[Cast] Jun 21 17:10:51 volumio-office volumio[835]: info: Adding audio output: Jun 21 17:10:51 volumio-office sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 21 17:10:51 volumio-office sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:51 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 21 17:10:51 volumio-office systemd[1]: shairport-sync.service: Succeeded. Jun 21 17:10:51 volumio-office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 21 17:10:51 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 21 17:10:51 volumio-office sudo[1305]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:51 volumio-office volumio[835]: info: Shairport-Sync Started Jun 21 17:10:51 volumio-office sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 21 17:10:51 volumio-office sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:52 volumio-office systemd[1]: Started MyVolumio SSH Tunnel. Jun 21 17:10:52 volumio-office sudo[1315]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:52 volumio-office volumio[835]: info: Remote SSH Started Jun 21 17:10:52 volumio-office autossh[1318]: port set to 0, monitoring disabled Jun 21 17:10:52 volumio-office autossh[1318]: starting ssh (count 1) Jun 21 17:10:52 volumio-office autossh[1318]: ssh child pid is 1321 Jun 21 17:10:52 volumio-office mpd[1286]: Jun 21 17:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 21 17:10:52 volumio-office systemd[1]: Started Music Player Daemon. Jun 21 17:10:52 volumio-office sudo[1268]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:52 volumio-office volumiossh-tunnel[1317]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. Jun 21 17:10:52 volumio-office volumio[835]: info: Successfully Updated MyVolumio device Jun 21 17:10:52 volumio-office volumio[835]: 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 21 17:10:52 volumio-office volumio[835]: error: updateQueue error: null Jun 21 17:10:52 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 21 17:10:52 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 21 17:10:52 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 21 17:10:52 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:52 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:53 volumio-office volumio[835]: SPOTIFY: SETTING SPOTIFY VOLUME 35 Jun 21 17:10:53 volumio-office volumio[835]: info: Sending Spotify command with payload to local API: /player/volume Jun 21 17:10:53 volumio-office go-librespot[1091]: time="2025-06-21T17:10:53+01:00" level=debug msg="update volume to 22937/65535" Jun 21 17:10:53 volumio-office go-librespot[1091]: time="2025-06-21T17:10:53+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 21 17:10:53 volumio-office go-librespot[1091]: time="2025-06-21T17:10:53+01:00" level=trace msg="emitting websocket event: volume" Jun 21 17:10:53 volumio-office volumio[835]: SPOTIFY: received: {"type":"volume","data":{"value":35,"max":100}} Jun 21 17:10:53 volumio-office volumio[835]: SPOTIFY: RECEIVED SPOTIFY VOLUME 35 Jun 21 17:10:53 volumio-office sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 21 17:10:53 volumio-office sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:53 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:53 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:53 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:53 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:53 volumio-office volumio[835]: info: Listing playlists Jun 21 17:10:53 volumio-office systemd[1]: Started Volumio Bluetooth Module. Jun 21 17:10:53 volumio-office sudo[1327]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:53 volumio-office volumio[835]: info: Volumio BT Module successfully started Jun 21 17:10:53 volumio-office sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jun 21 17:10:53 volumio-office sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:53 volumio-office volumiobt[1329]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jun 21 17:10:53 volumio-office sudo[1330]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:53 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61:3000 from 192.168.68.101 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Jun 21 17:10:53 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:53 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:53 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 21 17:10:53 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 21 17:10:53 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:53 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:54 volumio-office sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 17:10:54 volumio-office sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:54 volumio-office sudo[1334]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:54 volumio-office sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 17:10:54 volumio-office sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:54 volumio-office sudo[1336]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:54 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6 Pro Build/BP2A.250605.031.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 21 17:10:54 volumio-office sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jun 21 17:10:54 volumio-office sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:54 volumio-office volumiobt[1329]: pulseaudio: no process found Jun 21 17:10:54 volumio-office sudo[1339]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:54 volumio-office dbus-daemon[559]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.25' (uid=1000 pid=1343 comm="/usr/bin/pulseaudio --high-priority=true ") Jun 21 17:10:54 volumio-office systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jun 21 17:10:54 volumio-office volumio[835]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 21 17:10:54 volumio-office dbus-daemon[559]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jun 21 17:10:54 volumio-office systemd[1]: Started RealtimeKit Scheduling Policy Service. Jun 21 17:10:54 volumio-office rtkit-daemon[1344]: Successfully called chroot. Jun 21 17:10:54 volumio-office rtkit-daemon[1344]: Successfully dropped privileges. Jun 21 17:10:54 volumio-office rtkit-daemon[1344]: Successfully limited resources. Jun 21 17:10:54 volumio-office rtkit-daemon[1344]: Canary thread running. Jun 21 17:10:54 volumio-office rtkit-daemon[1344]: Watchdog thread running. Jun 21 17:10:54 volumio-office rtkit-daemon[1344]: Running. Jun 21 17:10:54 volumio-office dbus-daemon[559]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.26' (uid=0 pid=1344 comm="/usr/lib/rtkit/rtkit-daemon ") Jun 21 17:10:54 volumio-office systemd[1]: Starting Authorization Manager... Jun 21 17:10:54 volumio-office volumio[835]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 21 17:10:54 volumio-office sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 17:10:54 volumio-office sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:54 volumio-office sudo[1352]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:54 volumio-office polkitd[1348]: started daemon version 0.105 using authority implementation `local' version `0.105' Jun 21 17:10:54 volumio-office sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 17:10:54 volumio-office dbus-daemon[559]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jun 21 17:10:54 volumio-office systemd[1]: Started Authorization Manager. Jun 21 17:10:54 volumio-office sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 17:10:54 volumio-office sudo[1355]: pam_unix(sudo:session): session closed for user root Jun 21 17:10:54 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6 Pro Build/BP2A.250605.031.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: 15 Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetVisibleSources Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:55 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 17:10:55 volumio-office volumio[835]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 21 17:10:55 volumio-office volumio[835]: info: Received Get System Info Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 17:10:55 volumio-office volumio[835]: info: Discovery: Getting this device information Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:55 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:55 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:55 volumio-office volumio[835]: info: Listing playlists Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 21 17:10:55 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 21 17:10:55 volumio-office pulseaudio[1343]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 21 17:10:55 volumio-office pulseaudio[1343]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jun 21 17:10:55 volumio-office bluetoothd[784]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSource Jun 21 17:10:55 volumio-office bluetoothd[784]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSink Jun 21 17:10:55 volumio-office volumiobt[1329]: Applying permissions Jun 21 17:10:55 volumio-office volumiobt[1329]: Setting BT discoverable and pairable Jun 21 17:10:55 volumio-office pulseaudio[1343]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jun 21 17:10:55 volumio-office kernel: Bluetooth: RFCOMM TTY layer initialized Jun 21 17:10:55 volumio-office kernel: Bluetooth: RFCOMM socket layer initialized Jun 21 17:10:55 volumio-office kernel: Bluetooth: RFCOMM ver 1.11 Jun 21 17:10:55 volumio-office volumiobt[1329]: [CHG] Controller B8:27:EB:40:6B:AB Class: 0x0008041c Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [55B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [91B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [117B blob data] Jun 21 17:10:55 volumio-office volumiobt[1329]: [bluetooth]# -e power on Jun 21 17:10:55 volumio-office volumiobt[1329]: Invalid command in menu main: -e Jun 21 17:10:55 volumio-office volumiobt[1329]: Jun 21 17:10:55 volumio-office volumiobt[1329]: Use "help" for a list of available commands in a menu. Jun 21 17:10:55 volumio-office volumiobt[1329]: Use "menu " if you want to enter any submenu. Jun 21 17:10:55 volumio-office volumiobt[1329]: Use "back" if you want to return to menu main. Jun 21 17:10:55 volumio-office volumiobt[1329]: [bluetooth]# agent on Jun 21 17:10:55 volumio-office volumiobt[1329]: Agent is already registered Jun 21 17:10:55 volumio-office volumiobt[1329]: [bluetooth]# discoverable on Jun 21 17:10:55 volumio-office volumiobt[1329]: [bluetooth]# pairable on Jun 21 17:10:55 volumio-office volumiobt[1329]: [bluetooth]# agent NoInputNoOutput Jun 21 17:10:55 volumio-office volumiobt[1329]: Agent is already registered Jun 21 17:10:55 volumio-office volumiobt[1329]: [bluetooth]# default-agent Jun 21 17:10:55 volumio-office volumiobt[1329]: [bluetooth]# quit Jun 21 17:10:55 volumio-office volumiobt[1329]: [59B blob data] Jun 21 17:10:55 volumio-office volumio[835]: info: MRS: Getting audio outputs on start Jun 21 17:10:55 volumio-office volumio[835]: info: MRS: Requesting all other devices output Jun 21 17:10:56 volumio-office systemd[1]: systemd-timedated.service: Succeeded. Jun 21 17:10:56 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 21 17:10:56 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 17:10:56 volumio-office volumio[835]: info: Received Get System Info Jun 21 17:10:56 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 17:10:56 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 17:10:56 volumio-office volumio[835]: info: Discovery: Getting this device information Jun 21 17:10:56 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:56 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:56 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 17:10:57 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 17:10:57 volumio-office volumio[835]: info: Received Get System Info Jun 21 17:10:57 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 17:10:57 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 17:10:57 volumio-office volumio[835]: info: Discovery: Getting this device information Jun 21 17:10:57 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:57 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:10:57 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 17:10:58 volumio-office volumio[835]: info: BOOT COMPLETED Jun 21 17:10:58 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 21 17:10:58 volumio-office volumio[835]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Jun 21 17:10:58 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:10:58 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:11:00 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:11:00 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:11:02 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 17:11:02 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 17:11:02 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 21 17:11:02 volumio-office volumio-remote-updater[575]: No test mode Jun 21 17:11:02 volumio-office volumio-remote-updater[575]: No alpha test mode Jun 21 17:11:02 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 21 17:11:02 volumio-office volumio[835]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 21 17:11:02 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 21 17:11:03 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:11:03 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:11:03 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:11:03 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 17:11:03 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 21 17:11:03 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 21 17:11:03 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 21 17:11:03 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetBrowseSources Jun 21 17:11:03 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 17:11:04 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 21 17:11:10 volumio-office volumio[835]: info: CoreCommandRouter::volumioGetState Jun 21 17:11:10 volumio-office volumio[835]: info: CorePlayQueue::getTrack 0 Jun 21 17:11:10 volumio-office volumio[835]: info: Listing playlists Jun 21 17:11:10 volumio-office volumio[835]: info: Listing playlists Jun 21 17:11:11 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61 from 192.168.68.113 UA: unknown Engine version: 4 Transport: websocket Total Clients: 13 Jun 21 17:11:11 volumio-office volumio[835]: verbose: New Socket.io Connection to 192.168.68.61 from 192.168.68.113 UA: unknown Engine version: 4 Transport: websocket Total Clients: 13 Jun 21 17:11:11 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: multiroom , setServer Jun 21 17:11:11 volumio-office volumio[835]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setServer] in plugin multiroom Jun 21 17:11:11 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: multiroom , setClient Jun 21 17:11:11 volumio-office volumio[835]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setClient] in plugin multiroom Jun 21 17:11:11 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: multiroom , setClient Jun 21 17:11:11 volumio-office volumio[835]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setClient] in plugin multiroom Jun 21 17:11:11 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: multiroom , setClient Jun 21 17:11:11 volumio-office volumio[835]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setClient] in plugin multiroom Jun 21 17:11:11 volumio-office volumio[835]: info: CoreCommandRouter::executeOnPlugin: multiroom , setMultiroom Jun 21 17:11:11 volumio-office volumio[835]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setMultiroom] in plugin multiroom Jun 21 17:11:11 volumio-office volumio[835]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 17:11:11 volumio-office volumio[835]: TypeError: Cannot read property 'then' of undefined Jun 21 17:11:11 volumio-office volumio[835]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:826:20) Jun 21 17:11:11 volumio-office volumio[835]: at Socket.emit (events.js:315:20) Jun 21 17:11:11 volumio-office volumio[835]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 21 17:11:11 volumio-office volumio[835]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 21 17:11:11 volumio-office volumio[835]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 17:11:12 volumio-office sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-21 17:10 Jun 21 17:11:12 volumio-office sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"