May 29 11:30:50 volumio ntpd[939]: CLOCK: time stepped by 511104.677029 May 29 11:30:50 volumio ntpd[939]: CLOCK: time changed from 2025-05-23 to 2025-05-29 May 29 11:30:50 volumio ntpd[939]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 29 11:30:50 volumio volumio[1088]: MPD error: Cannot assign requested address May 29 11:30:50 volumio volumio[1088]: info: Cannot read play queue from file May 29 11:30:50 volumio volumio[1088]: info: Volumio Network Manager: Network status updated: 1 May 29 11:30:51 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 29 11:30:51 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... May 29 11:30:51 volumio volumio-remote-updater[701]: [2025-05-29 06:00:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747987342 101 May 29 11:30:51 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 29 11:30:51 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. May 29 11:30:51 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. May 29 11:30:51 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 29 11:30:51 volumio volumio[1088]: 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: 1 May 29 11:30:51 volumio volumio[1088]: info: VolumeController:: Volume=86 Mute =false May 29 11:30:51 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:30:51 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:51 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 11:30:51 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:30:51 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock May 29 11:30:51 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock May 29 11:30:51 volumio volumio[1088]: info: CoreCommandRouter::volumioRetrievevolume May 29 11:30:51 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 29 11:30:51 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 29 11:30:51 volumio volumio[1088]: info: Setting Device type: Raspberry PI May 29 11:30:51 volumio volumio[1088]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 29 11:30:51 volumio volumio[1088]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03114 May 29 11:30:51 volumio volumio[1088]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 29 11:30:51 volumio volumio[1088]: info: VolumeController:: Volume=86 Mute =false May 29 11:30:51 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:30:51 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:51 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:30:51 volumio sudo[1356]: pam_unix(sudo:session): session closed for user root May 29 11:30:51 volumio volumio[1088]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 29 11:30:51 volumio volumio[1088]: info: CoreStateMachine::getcurrentVolume May 29 11:30:51 volumio volumio[1088]: info: CoreCommandRouter::volumioRetrievevolume May 29 11:30:51 volumio systemd[1]: Reloading. May 29 11:30:51 volumio volumio[1088]: info: Discovery: A device disappeared from network May 29 11:30:51 volumio volumio[1088]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') May 29 11:30:52 volumio volumio[1088]: info: Discovery: A device disappeared from network May 29 11:30:52 volumio volumio[1088]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') May 29 11:30:52 volumio volumio[1088]: info: Discovery: adding d406bc52-da2b-41cc-ba7f-076c69e255de May 29 11:30:52 volumio volumio[1088]: info: Discovery: Found device Volumio May 29 11:30:52 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:30:52 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:52 volumio volumio[1088]: info: Discovery: this is already registered, d406bc52-da2b-41cc-ba7f-076c69e255de May 29 11:30:52 volumio volumio[1088]: info: Discovery: Found device Volumio May 29 11:30:52 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:30:52 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:52 volumio volumio[1088]: info: VolumeController:: Volume=86 Mute =false May 29 11:30:52 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:30:52 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:52 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:30:52 volumio volumio[1088]: info: CoreStateMachine::updateTrackBlock May 29 11:30:52 volumio volumio[1088]: info: CorePlayQueue::getTrackBlock May 29 11:30:52 volumio volumio[1088]: info: CoreCommandRouter::volumioRetrievevolume May 29 11:30:52 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:52 volumio volumio[1088]: info: Discovery: A device disappeared from network May 29 11:30:52 volumio volumio[1088]: info: Discovery: Device volumio disappeared from network May 29 11:30:52 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:30:52 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:30:52 volumio volumio[1088]: info: Discovery: adding d406bc52-da2b-41cc-ba7f-076c69e255de May 29 11:30:52 volumio volumio[1088]: info: Discovery: Found device Volumio May 29 11:30:52 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:30:52 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:52 volumio volumio[1088]: info: Completed loading Core Plugins May 29 11:30:52 volumio volumio[1088]: info: Preparing to generate the ALSA configuration file May 29 11:30:52 volumio volumio[1088]: info: VolumeController:: Volume=86 Mute =false May 29 11:30:52 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:30:52 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:52 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:30:53 volumio volumio[1088]: info: Discovery: this is already registered, d406bc52-da2b-41cc-ba7f-076c69e255de May 29 11:30:53 volumio volumio[1088]: info: Discovery: Found device Volumio May 29 11:30:53 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:30:53 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:53 volumio volumio[1088]: info: Asound.conf file written May 29 11:30:53 volumio firststart.sh[1615]: rescue-ssh.target is a disabled or a static unit not running, not starting it. May 29 11:30:53 volumio firststart.sh[1615]: ssh.service is a disabled or a static unit not running, not starting it. May 29 11:30:53 volumio volumio[1462]: Starting albumart workers May 29 11:30:53 volumio sudo[1622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 29 11:30:53 volumio sudo[1622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:53 volumio sudo[1622]: pam_unix(sudo:session): session closed for user root May 29 11:30:53 volumio volumio[1088]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 29 11:30:53 volumio volumio[1088]: No state is present for card b1 May 29 11:30:53 volumio volumio[1088]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 29 11:30:53 volumio volumio[1088]: /usr/share/alsa/init/bcm2835:14: control element not found May 29 11:30:53 volumio volumio[1088]: No state is present for card b1 May 29 11:30:53 volumio volumio[1088]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 29 11:30:53 volumio volumio[1088]: No state is present for card Headphones May 29 11:30:53 volumio volumio[1088]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 29 11:30:53 volumio volumio[1088]: /usr/share/alsa/init/bcm2835:20: control element not found May 29 11:30:53 volumio volumio[1088]: No state is present for card Headphones May 29 11:30:53 volumio volumio[1088]: info: Output device has changed, restarting MPD May 29 11:30:53 volumio volumio[1088]: info: Output device has changed, restarting Shairport Sync May 29 11:30:53 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:53 volumio sudo[1635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 11:30:53 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:53 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 11:30:53 volumio dhcpcd[797]: wlan0: using IPv4LL address 169.254.46.188 May 29 11:30:53 volumio avahi-daemon[690]: Registering new address record for 169.254.46.188 on wlan0.IPv4. May 29 11:30:53 volumio dhcpcd[797]: wlan0: adding route to 169.254.0.0/16 May 29 11:30:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 29 11:30:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 29 11:30:53 volumio sudo[1633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 11:30:53 volumio sudo[1633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:53 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 11:30:53 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 29 11:30:53 volumio systemd[1]: welcome.service: Deactivated successfully. May 29 11:30:53 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 29 11:30:53 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 29 11:30:53 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 29 11:30:53 volumio welcome[1660]: Resolved ip:[3] 192.168.68.62 192.168.211.1 169.254.46.188 May 29 11:30:53 volumio volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 11:30:53 volumio volumio[1088]: info: ___________ START PLUGINS ___________ May 29 11:30:53 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 29 11:30:53 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 11:30:53 volumio sudo[1633]: pam_unix(sudo:session): session closed for user root May 29 11:30:53 volumio volumio[1088]: info: Setting Hotspot Unique name on first start: Volumio-70390 May 29 11:30:53 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam May 29 11:30:53 volumio volumio[1088]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network May 29 11:30:53 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings May 29 11:30:53 volumio volumio[1088]: info: CoreCommandRouter::Reload Ui May 29 11:30:53 volumio volumio[1088]: info: ControllerMpd::onStart: Initializing MPD May 29 11:30:53 volumio sudo[1655]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 11:30:53 volumio sudo[1655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 11:30:53 volumio sudo[1667]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 29 11:30:53 volumio sudo[1655]: pam_unix(sudo:session): session closed for user root May 29 11:30:53 volumio volumio[1088]: info: Creating MPD Configuration file May 29 11:30:53 volumio systemd[1]: Reloading. May 29 11:30:53 volumio sudo[1670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 11:30:53 volumio sudo[1670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:53 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 11:30:53 volumio volumio[1088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 11:30:53 volumio volumio[1088]: info: [1748498453935] CoreMusicLibrary::Adding element Media Servers May 29 11:30:53 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 11:30:53 volumio sudo[1670]: pam_unix(sudo:session): session closed for user root May 29 11:30:54 volumio sudo[1674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 11:30:54 volumio sudo[1674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:54 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:54 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 11:30:54 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:30:54 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:30:54 volumio volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 11:30:54 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:54 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 11:30:54 volumio volumio[1463]: Starting albumart workers May 29 11:30:54 volumio volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 11:30:54 volumio volumio[1088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 11:30:54 volumio volumio[1088]: info: [1748498454623] CoreMusicLibrary::Adding element Last_100 May 29 11:30:54 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 11:30:54 volumio volumio[1088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 11:30:54 volumio volumio[1088]: info: [1748498454635] CoreMusicLibrary::Adding element Webradio May 29 11:30:54 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 11:30:54 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 11:30:54 volumio volumio[1088]: info: Initializing BBC Radios May 29 11:30:54 volumio ntpd[939]: IO: Listen normally on 5 wlan0 169.254.46.188:123 May 29 11:30:54 volumio ntpd[939]: IO: new interface(s) found: waking up resolver May 29 11:30:55 volumio systemd[1]: mpd.service: Deactivated successfully. May 29 11:30:55 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 29 11:30:55 volumio systemd[1]: mpd.socket: Deactivated successfully. May 29 11:30:55 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 29 11:30:55 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 29 11:30:55 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 11:30:55 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 11:30:55 volumio volumio[1461]: Starting albumart workers May 29 11:30:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 29 11:30:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 29 11:30:55 volumio volumio[1088]: info: Volumio Calling Home May 29 11:30:55 volumio firststart.sh[1708]: ssh.socket is a disabled or a static unit not running, not starting it. May 29 11:30:55 volumio sudo[1717]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 11:30:55 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 11:30:55 volumio sudo[1724]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 29 11:30:55 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root May 29 11:30:55 volumio firststart.sh[1089]: Enabling SSH for first boot May 29 11:30:55 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... May 29 11:30:55 volumio sshd[1728]: Server listening on 0.0.0.0 port 22. May 29 11:30:55 volumio sshd[1728]: Server listening on :: port 22. May 29 11:30:55 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. May 29 11:30:55 volumio firststart.sh[1089]: Disabling firststart service May 29 11:30:55 volumio systemctl[1729]: Removed "/etc/systemd/system/multi-user.target.wants/firststart.service". May 29 11:30:55 volumio systemd[1]: Reloading. May 29 11:30:56 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:30:56 volumio systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:30:56 volumio firststart.sh[1089]: Finalizing May 29 11:30:57 volumio systemd[1]: firststart.service: Deactivated successfully. May 29 11:30:57 volumio systemd[1]: firststart.service: Consumed 8.458s CPU time. May 29 11:30:57 volumio volumio[1088]: info: Discovery: this is already registered, d406bc52-da2b-41cc-ba7f-076c69e255de May 29 11:30:57 volumio volumio[1088]: info: Discovery: Found device Volumio May 29 11:30:57 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:30:57 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:57 volumio volumio[1088]: info: Discovery: this is already registered, d406bc52-da2b-41cc-ba7f-076c69e255de May 29 11:30:57 volumio volumio[1088]: info: Discovery: Found device Volumio May 29 11:30:57 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:30:57 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:30:57 volumio sudo[1767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf May 29 11:30:57 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:57 volumio volumio[1088]: info: MPD Permissions set May 29 11:30:57 volumio volumio[1088]: info: MPD Permissions set May 29 11:30:57 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root May 29 11:30:57 volumio volumio[1088]: info: Volumio called home May 29 11:30:57 volumio volumio[1088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 29 11:30:57 volumio volumio[1088]: info: Permissions for /etc/hostapd/hostapd.conf May 29 11:30:57 volumio sudo[1785]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 29 11:30:57 volumio sudo[1785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:57 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 29 11:30:57 volumio volumio[1088]: info: Discovery: Restarting Advertising due to device name change May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio systemd[1]: Starting wireless.service - Wireless Services... May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 11:30:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:30:58 volumio sudo[1810]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 11:30:58 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:58 volumio sudo[1808]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 11:30:58 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root May 29 11:30:58 volumio sudo[1808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:58 volumio sudo[1808]: pam_unix(sudo:session): session closed for user root May 29 11:30:58 volumio volumio[1088]: info: Starting Shairport Sync May 29 11:30:58 volumio volumio[1088]: info: Starting Shairport Sync May 29 11:30:59 volumio sudo[1814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 11:30:59 volumio sudo[1814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:59 volumio volumio[1088]: info: Starting Shairport Sync May 29 11:30:59 volumio sudo[1819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 11:30:59 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:59 volumio sudo[1816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 11:30:59 volumio sudo[1816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:30:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 29 11:30:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 29 11:30:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 11:30:59 volumio systemd[1]: shairport-sync.service: Consumed 1.779s CPU time. May 29 11:30:59 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 11:30:59 volumio mpd[1725]: 2025-05-29T06:00:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 11:30:59 volumio mpd[1725]: 2025-05-29T06:00:59 exception: Database corrupted May 29 11:30:59 volumio sudo[1814]: pam_unix(sudo:session): session closed for user root May 29 11:30:59 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 29 11:30:59 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root May 29 11:30:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 29 11:30:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 29 11:30:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 11:30:59 volumio sudo[1674]: pam_unix(sudo:session): session closed for user root May 29 11:30:59 volumio volumio[1088]: info: Shairport-Sync Started May 29 11:30:59 volumio volumio[1088]: Error adding Membership: Error: addMembership EINVAL May 29 11:30:59 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 11:30:59 volumio volumio[1088]: info: Discovery: A device disappeared from network May 29 11:30:59 volumio volumio[1088]: info: Discovery: Device volumio disappeared from network May 29 11:30:59 volumio sudo[1819]: pam_unix(sudo:session): session closed for user root May 29 11:30:59 volumio sudo[1816]: pam_unix(sudo:session): session closed for user root May 29 11:30:59 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... May 29 11:30:59 volumio dnsmasq[1112]: exiting on receipt of SIGTERM May 29 11:30:59 volumio systemd[1]: dnsmasq.service: Deactivated successfully. May 29 11:30:59 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. May 29 11:30:59 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... May 29 11:30:59 volumio volumio[1088]: info: Completed starting Core Plugins May 29 11:30:59 volumio volumio[1088]: info: ------------------------------------------- May 29 11:30:59 volumio volumio[1088]: info: ----- MyVolumio plugins startup ---- May 29 11:30:59 volumio volumio[1088]: info: ------------------------------------------- May 29 11:30:59 volumio volumio[1088]: info: [MyVolumio PluginManager] Fetching plans data.... May 29 11:30:59 volumio volumio[1088]: info: Discovery: A device disappeared from network May 29 11:30:59 volumio volumio[1088]: error: MPD error: The expression evaluated to a falsy value: May 29 11:30:59 volumio volumio[1088]: assert.ok(self.idling) May 29 11:30:59 volumio volumio[1088]: error: The expression evaluated to a falsy value: May 29 11:30:59 volumio volumio[1088]: assert.ok(self.idling) May 29 11:30:59 volumio volumio[1088]: info: Shairport-Sync Started May 29 11:30:59 volumio volumio[1088]: info: Shairport-Sync Started May 29 11:30:59 volumio dhcpcd[797]: wlan0: carrier lost May 29 11:31:00 volumio volumio[1088]: info: Discovery: A device disappeared from network May 29 11:31:00 volumio avahi-daemon[690]: Withdrawing address record for 169.254.46.188 on wlan0. May 29 11:31:00 volumio dhcpcd[797]: wlan0: deleting route to 169.254.0.0/16 May 29 11:31:00 volumio systemd[1]: hostapd.service: Deactivated successfully. May 29 11:31:00 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. May 29 11:31:00 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 11:31:00 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 29 11:31:00 volumio systemd[1]: welcome.service: Deactivated successfully. May 29 11:31:00 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 29 11:31:00 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 29 11:31:00 volumio sudo[1866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 29 11:31:00 volumio sudo[1866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:00 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 29 11:31:00 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:31:00 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:00 volumio welcome[1902]: Resolved ip:[2] 192.168.68.62 192.168.211.1 May 29 11:31:00 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 29 11:31:00 volumio volumio[1088]: info: MPD running with PID1725 May 29 11:31:00 volumio volumio[1088]: ,establishing connection May 29 11:31:00 volumio volumio[1088]: error: updateQueue error: null May 29 11:31:00 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 29 11:31:00 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 11:31:00 volumio sudo[1866]: pam_unix(sudo:session): session closed for user root May 29 11:31:00 volumio sudo[1919]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 29 11:31:00 volumio sudo[1919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 11:31:00 volumio avahi-daemon[690]: Withdrawing address record for 192.168.211.1 on wlan0. May 29 11:31:00 volumio avahi-daemon[690]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 29 11:31:00 volumio avahi-daemon[690]: Interface wlan0.IPv4 no longer relevant for mDNS. May 29 11:31:00 volumio sudo[1919]: pam_unix(sudo:session): session closed for user root May 29 11:31:00 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 11:31:00 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 29 11:31:00 volumio systemd[1]: welcome.service: Deactivated successfully. May 29 11:31:00 volumio volumio[1088]: info: Upmpdcli Daemon Started May 29 11:31:00 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 29 11:31:00 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 29 11:31:00 volumio volumio[1088]: error: updateQueue error: null May 29 11:31:00 volumio sudo[1932]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 29 11:31:00 volumio sudo[1932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 11:31:00 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 29 11:31:01 volumio sudo[1932]: pam_unix(sudo:session): session closed for user root May 29 11:31:01 volumio welcome[1933]: Resolved ip:[1] 192.168.68.62 May 29 11:31:01 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 29 11:31:01 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.399652, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 29 11:31:01 volumio nmbd[998]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.399816, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 29 11:31:01 volumio nmbd[998]: retransmit_or_expire_response_records: Failed to resend packet id 31840 to IP 169.254.255.255 on subnet 169.254.46.188 May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.399871, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 29 11:31:01 volumio nmbd[998]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.399916, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 29 11:31:01 volumio nmbd[998]: retransmit_or_expire_response_records: Failed to resend packet id 31841 to IP 169.254.255.255 on subnet 169.254.46.188 May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.399962, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 29 11:31:01 volumio nmbd[998]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.400006, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 29 11:31:01 volumio nmbd[998]: retransmit_or_expire_response_records: Failed to resend packet id 31842 to IP 169.254.255.255 on subnet 169.254.46.188 May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.400050, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 29 11:31:01 volumio nmbd[998]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.400092, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 29 11:31:01 volumio nmbd[998]: retransmit_or_expire_response_records: Failed to resend packet id 31843 to IP 169.254.255.255 on subnet 169.254.46.188 May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.400135, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 29 11:31:01 volumio nmbd[998]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.400178, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 29 11:31:01 volumio nmbd[998]: retransmit_or_expire_response_records: Failed to resend packet id 31844 to IP 169.254.255.255 on subnet 169.254.46.188 May 29 11:31:01 volumio nmbd[998]: [2025/05/29 06:01:01.400456, 0] ../../source3/libsmb/nmblib.c:923(send_udp) May 29 11:31:01 volumio nmbd[998]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable May 29 11:31:01 volumio sudo[1951]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 29 11:31:01 volumio sudo[1951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:01 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 29 11:31:01 volumio sudo[1951]: pam_unix(sudo:session): session closed for user root May 29 11:31:01 volumio sudo[1953]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 29 11:31:01 volumio sudo[1953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:01 volumio sudo[1953]: pam_unix(sudo:session): session closed for user root May 29 11:31:01 volumio sudo[1961]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 29 11:31:01 volumio sudo[1961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:01 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. May 29 11:31:01 volumio sudo[1961]: pam_unix(sudo:session): session closed for user root May 29 11:31:01 volumio sudo[1963]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 29 11:31:01 volumio sudo[1963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:02 volumio ntpd[939]: IO: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=4, dropped=0, active_time=20 secs May 29 11:31:02 volumio ntpd[939]: IO: Deleting interface #5 wlan0, 169.254.46.188#123, interface stats: received=0, sent=0, dropped=0, active_time=8 secs May 29 11:31:02 volumio volumio[1088]: info: Discovery: Started advertising with name: Volumio May 29 11:31:04 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 29 11:31:04 volumio sudo[1963]: pam_unix(sudo:session): session closed for user root May 29 11:31:04 volumio sudo[1979]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 29 11:31:04 volumio sudo[1979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:04 volumio sudo[1979]: pam_unix(sudo:session): session closed for user root May 29 11:31:04 volumio sudo[1981]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US May 29 11:31:04 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:04 volumio sudo[1981]: pam_unix(sudo:session): session closed for user root May 29 11:31:05 volumio sudo[1988]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 29 11:31:05 volumio sudo[1988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 11:31:05 volumio sudo[1988]: pam_unix(sudo:session): session closed for user root May 29 11:31:05 volumio sudo[1990]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 29 11:31:05 volumio sudo[1990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 11:31:05 volumio sudo[1990]: pam_unix(sudo:session): session closed for user root May 29 11:31:05 volumio avahi-daemon[690]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 29 11:31:05 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 11:31:05 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 29 11:31:05 volumio systemd[1]: welcome.service: Deactivated successfully. May 29 11:31:05 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 29 11:31:05 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 29 11:31:05 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 29 11:31:05 volumio avahi-daemon[690]: New relevant interface wlan0.IPv4 for mDNS. May 29 11:31:05 volumio avahi-daemon[690]: Registering new address record for 192.168.211.1 on wlan0.IPv4. May 29 11:31:05 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 29 11:31:05 volumio welcome[1995]: Resolved ip:[2] 192.168.68.62 192.168.211.1 May 29 11:31:05 volumio systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... May 29 11:31:05 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 29 11:31:05 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 11:31:05 volumio dhcpcd[797]: wlan0: carrier acquired May 29 11:31:05 volumio hostapd[2003]: wlan0: interface state UNINITIALIZED->ENABLED May 29 11:31:05 volumio hostapd[2003]: wlan0: AP-ENABLED May 29 11:31:05 volumio systemd[1]: hostapd.service: Can't open PID file /run/hostapd.pid (yet?) after start: No such file or directory May 29 11:31:05 volumio systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. May 29 11:31:05 volumio dhcpcd[797]: wlan0: IAID 01:68:8c:49 May 29 11:31:06 volumio systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... May 29 11:31:06 volumio iwconfig[2013]: Error for wireless request "Set Power Management" (8B2C) : May 29 11:31:06 volumio iwconfig[2013]: SET failed on device wlan0 ; Invalid argument. May 29 11:31:06 volumio systemd[1]: wireless.service: Deactivated successfully. May 29 11:31:06 volumio systemd[1]: Started wireless.service - Wireless Services. May 29 11:31:06 volumio systemd[1]: wireless.service: Consumed 11.420s CPU time. May 29 11:31:06 volumio sudo[1785]: pam_unix(sudo:session): session closed for user root May 29 11:31:06 volumio dnsmasq[2016]: started, version 2.90 cachesize 150 May 29 11:31:06 volumio dnsmasq[2016]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile May 29 11:31:06 volumio dnsmasq-dhcp[2016]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d May 29 11:31:06 volumio dnsmasq[2016]: reading /etc/resolv.conf May 29 11:31:06 volumio dnsmasq[2016]: using nameserver 8.8.8.8#53 May 29 11:31:06 volumio dnsmasq[2016]: using nameserver 8.8.4.4#53 May 29 11:31:06 volumio dnsmasq[2016]: using nameserver 208.67.222.222#53 May 29 11:31:06 volumio dnsmasq[2016]: using nameserver 208.67.220.220#53 May 29 11:31:06 volumio dnsmasq[2016]: read /etc/hosts - 18 names May 29 11:31:06 volumio systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. May 29 11:31:06 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 29 11:31:06 volumio dhcpcd[797]: wlan0: soliciting an IPv6 router May 29 11:31:06 volumio volumio[1088]: info: MPD Database updated - AlbumList cache refreshed May 29 11:31:06 volumio volumio[1088]: info: May 29 11:31:06 volumio volumio[1088]: ---------------------------- MPD announces state update: database May 29 11:31:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 29 11:31:06 volumio volumio[1088]: info: May 29 11:31:06 volumio volumio[1088]: ---------------------------- MPD announces state update: update May 29 11:31:06 volumio volumio[1088]: info: ControllerMpd::getState May 29 11:31:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 29 11:31:06 volumio volumio[1088]: info: ControllerMpd::getState May 29 11:31:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 29 11:31:06 volumio volumio[1088]: info: MPD Database updated - AlbumList cache refreshed May 29 11:31:06 volumio volumio[1088]: info: May 29 11:31:06 volumio volumio[1088]: ---------------------------- MPD announces state update: database May 29 11:31:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 29 11:31:06 volumio volumio[1088]: info: May 29 11:31:06 volumio volumio[1088]: ---------------------------- MPD announces state update: update May 29 11:31:06 volumio volumio[1088]: info: ControllerMpd::getState May 29 11:31:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 29 11:31:06 volumio volumio[1088]: info: ControllerMpd::getState May 29 11:31:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 29 11:31:06 volumio volumio[1088]: info: MPD Database updated - AlbumList cache refreshed May 29 11:31:06 volumio volumio[1088]: info: May 29 11:31:06 volumio volumio[1088]: ---------------------------- MPD announces state update: database May 29 11:31:06 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 29 11:31:06 volumio volumio[1088]: info: May 29 11:31:06 volumio volumio[1088]: ---------------------------- MPD announces state update: update May 29 11:31:06 volumio volumio[1088]: info: ControllerMpd::getState May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 29 11:31:07 volumio volumio[1088]: info: ControllerMpd::getState May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status May 29 11:31:07 volumio volumio[1088]: info: sendMpdCommand status took 125 milliseconds May 29 11:31:07 volumio volumio[1088]: info: sendMpdCommand status took 125 milliseconds May 29 11:31:07 volumio volumio[1088]: info: sendMpdCommand status took 124 milliseconds May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::parseState May 29 11:31:07 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::parseState May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::parseState May 29 11:31:07 volumio volumio[1088]: info: ControllerMpd::pushState May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 29 11:31:07 volumio volumio[1088]: verbose: CURRENT POSITION 0 May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: No code May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: ControllerMpd::pushState May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 29 11:31:07 volumio volumio[1088]: verbose: CURRENT POSITION 0 May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: No code May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: ------------------------------ 549ms May 29 11:31:07 volumio volumio[1088]: info: ------------------------------ 549ms May 29 11:31:07 volumio volumio[1088]: info: sendMpdCommand status took 564 milliseconds May 29 11:31:07 volumio volumio[1088]: info: sendMpdCommand status took 560 milliseconds May 29 11:31:07 volumio volumio[1088]: info: sendMpdCommand status took 557 milliseconds May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::parseState May 29 11:31:07 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse May 29 11:31:07 volumio dhcpcd[797]: wlan0: soliciting a DHCP lease May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::parseState May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::parseState May 29 11:31:07 volumio volumio[1088]: info: ControllerMpd::pushState May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 29 11:31:07 volumio volumio[1088]: verbose: CURRENT POSITION 0 May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: No code May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: ControllerMpd::pushState May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 29 11:31:07 volumio volumio[1088]: verbose: CURRENT POSITION 0 May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: No code May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio ntpd[939]: IO: Listen normally on 6 wlan0 192.168.211.1:123 May 29 11:31:07 volumio ntpd[939]: IO: new interface(s) found: waking up resolver May 29 11:31:07 volumio volumio[1088]: info: ------------------------------ 785ms May 29 11:31:07 volumio volumio[1088]: info: ------------------------------ 784ms May 29 11:31:07 volumio volumio[1088]: info: sendMpdCommand status took 809 milliseconds May 29 11:31:07 volumio volumio[1088]: info: sendMpdCommand status took 820 milliseconds May 29 11:31:07 volumio volumio[1088]: info: sendMpdCommand status took 815 milliseconds May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::parseState May 29 11:31:07 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::parseState May 29 11:31:07 volumio volumio[1088]: verbose: ControllerMpd::parseState May 29 11:31:07 volumio volumio[1088]: info: ControllerMpd::pushState May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 29 11:31:07 volumio volumio[1088]: verbose: CURRENT POSITION 0 May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: No code May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: ControllerMpd::pushState May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::servicePushState May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 29 11:31:07 volumio volumio[1088]: verbose: CURRENT POSITION 0 May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: No code May 29 11:31:07 volumio volumio[1088]: info: CoreStateMachine::pushState May 29 11:31:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState May 29 11:31:07 volumio volumio[1088]: info: ------------------------------ 990ms May 29 11:31:07 volumio volumio[1088]: info: ------------------------------ 994ms May 29 11:31:08 volumio sudo[2048]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 11:31:08 volumio sudo[2046]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 11:31:08 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:08 volumio sudo[2048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:08 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root May 29 11:31:08 volumio sudo[2048]: pam_unix(sudo:session): session closed for user root May 29 11:31:08 volumio volumio[1088]: verbose: New Socket.io Connection to 192.168.68.62 from 192.168.68.61 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::volumioGetVisibleSources May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 29 11:31:08 volumio volumio[1088]: info: Received Get System Info May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 11:31:08 volumio volumio[1088]: info: Discovery: Getting this device information May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:31:08 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 11:31:08 volumio volumio-remote-updater[701]: No test mode May 29 11:31:08 volumio volumio-remote-updater[701]: No alpha test mode May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 11:31:09 volumio volumio[1088]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 11:31:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 11:31:09 volumio volumio[1088]: info: Discovery: adding d406bc52-da2b-41cc-ba7f-076c69e255de May 29 11:31:09 volumio volumio[1088]: info: Discovery: Found device Volumio May 29 11:31:09 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:31:09 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:09 volumio volumio[1088]: info: Discovery: this is already registered, d406bc52-da2b-41cc-ba7f-076c69e255de May 29 11:31:09 volumio volumio[1088]: info: Discovery: Found device Volumio May 29 11:31:09 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:31:09 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:09 volumio volumio[1088]: info: Discovery: this is already registered, d406bc52-da2b-41cc-ba7f-076c69e255de May 29 11:31:09 volumio volumio[1088]: info: Discovery: Found device Volumio May 29 11:31:09 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:31:09 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 29 11:31:09 volumio volumio[1088]: info: Loading i18n strings for locale en May 29 11:31:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 29 11:31:09 volumio volumio[1088]: info: Setting timezone to Asia/Calcutta May 29 11:31:09 volumio sudo[2056]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 29 11:31:09 volumio sudo[2056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:09 volumio sudo[2056]: pam_unix(sudo:session): session closed for user root May 29 11:31:10 volumio sudo[2060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Calcutta /etc/localtime May 29 11:31:10 volumio sudo[2060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:10 volumio sudo[2060]: pam_unix(sudo:session): session closed for user root May 29 11:31:10 volumio sudo[2064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 29 11:31:10 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:10 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root May 29 11:31:10 volumio sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Calcutta May 29 11:31:10 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:10 volumio dbus-daemon[692]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.29' (uid=0 pid=2069 comm="/usr/bin/timedatectl set-timezone Asia/Calcutta") May 29 11:31:10 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 29 11:31:10 volumio dbus-daemon[692]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 11:31:10 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 29 11:31:10 volumio sudo[2068]: pam_unix(sudo:session): session closed for user root May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 29 11:31:10 volumio volumio[1088]: info: Adding plugin bluetooth to MyMusic Plugins May 29 11:31:10 volumio volumio[1088]: info: Adding plugin multiroom to MyMusic Plugins May 29 11:31:10 volumio volumio[1088]: info: Adding plugin metavolumio to MyMusic Plugins May 29 11:31:10 volumio volumio[1088]: info: Adding plugin cd_controller to MyMusic Plugins May 29 11:31:10 volumio volumio[1088]: info: Adding plugin smart_inputs to MyMusic Plugins May 29 11:31:10 volumio volumio[1088]: info: Adding plugin tidalconnect to MyMusic Plugins May 29 11:31:10 volumio volumio[1088]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 29 11:31:12 volumio dhcpcd[797]: wlan0: probing for an IPv4LL address May 29 11:31:13 volumio volumio[1088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 29 11:31:13 volumio volumio[1088]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 29 11:31:13 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 11:31:13 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 11:31:13 volumio volumio[1088]: info: Starting MyVolumio Remote Streaming Endpoints May 29 11:31:13 volumio volumio[1088]: info: MyVolumio not started May 29 11:31:13 volumio volumio[1088]: info: Initializing device activation check May 29 11:31:13 volumio volumio[1088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 29 11:31:13 volumio volumio[1088]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 29 11:31:15 volumio upmpdcli[2072]: writing RSA key May 29 11:31:15 volumio volumio[1088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 29 11:31:15 volumio volumio[1088]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 29 11:31:15 volumio volumio[1088]: info: Streaming services startup May 29 11:31:15 volumio volumio[1088]: info: Starting Streaming Daemon May 29 11:31:15 volumio volumio[1088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 29 11:31:15 volumio sudo[2077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 11:31:15 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection May 29 11:31:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo May 29 11:31:15 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root May 29 11:31:15 volumio volumio[1088]: error: Cannot start Volumio Streaming Daemon May 29 11:31:15 volumio volumio[1088]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 11:31:15 volumio volumio[1088]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 11:31:15 volumio sudo[2084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 11:31:15 volumio sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:15 volumio sudo[2084]: pam_unix(sudo:session): session closed for user root May 29 11:31:15 volumio volumio[1088]: Updating browse sources language May 29 11:31:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 11:31:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 29 11:31:15 volumio volumio[1088]: info: Loading i18n strings for locale en May 29 11:31:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 29 11:31:15 volumio volumio[1088]: info: Setting timezone to Asia/Calcutta May 29 11:31:15 volumio sudo[2091]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 29 11:31:15 volumio sudo[2091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:15 volumio sudo[2091]: pam_unix(sudo:session): session closed for user root May 29 11:31:15 volumio sudo[2095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Calcutta /etc/localtime May 29 11:31:15 volumio sudo[2095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:15 volumio sudo[2095]: pam_unix(sudo:session): session closed for user root May 29 11:31:15 volumio sudo[2099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 29 11:31:15 volumio sudo[2099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:15 volumio sudo[2099]: pam_unix(sudo:session): session closed for user root May 29 11:31:15 volumio sudo[2103]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Calcutta May 29 11:31:15 volumio sudo[2103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:15 volumio sudo[2103]: pam_unix(sudo:session): session closed for user root May 29 11:31:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 29 11:31:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 29 11:31:15 volumio sudo[2106]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 29 11:31:15 volumio sudo[2106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:15 volumio volumio[1088]: Updating browse sources language May 29 11:31:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 11:31:15 volumio volumio[1088]: info: Fetching Streaming Services browse cache May 29 11:31:16 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 29 11:31:16 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 29 11:31:16 volumio volumio[1088]: info: Refreshing Cached IP Addresses May 29 11:31:16 volumio sudo[2112]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 29 11:31:16 volumio sudo[2112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:16 volumio sudo[2112]: pam_unix(sudo:session): session closed for user root May 29 11:31:16 volumio systemd[1]: upmpdcli.service: Deactivated successfully. May 29 11:31:16 volumio systemd[1]: upmpdcli.service: Consumed 13.514s CPU time. May 29 11:31:16 volumio sudo[2114]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 11:31:16 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:16 volumio sudo[2114]: pam_unix(sudo:session): session closed for user root May 29 11:31:16 volumio sudo[2116]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 11:31:16 volumio sudo[2116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:16 volumio sudo[2116]: pam_unix(sudo:session): session closed for user root May 29 11:31:17 volumio dhcpcd[797]: wlan0: using IPv4LL address 169.254.46.188 May 29 11:31:17 volumio avahi-daemon[690]: Registering new address record for 169.254.46.188 on wlan0.IPv4. May 29 11:31:17 volumio dhcpcd[797]: wlan0: adding route to 169.254.0.0/16 May 29 11:31:17 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 11:31:17 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 29 11:31:17 volumio systemd[1]: welcome.service: Deactivated successfully. May 29 11:31:17 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 29 11:31:17 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 29 11:31:17 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 29 11:31:17 volumio welcome[2131]: Resolved ip:[3] 192.168.68.62 192.168.211.1 169.254.46.188 May 29 11:31:17 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 29 11:31:17 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 29 11:31:18 volumio ntpd[939]: IO: Listen normally on 7 wlan0 169.254.46.188:123 May 29 11:31:18 volumio ntpd[939]: IO: new interface(s) found: waking up resolver May 29 11:31:19 volumio sudo[2155]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 11:31:19 volumio sudo[2157]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 11:31:19 volumio sudo[2155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:19 volumio sudo[2155]: pam_unix(sudo:session): session closed for user root May 29 11:31:19 volumio sudo[2157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:19 volumio sudo[2157]: pam_unix(sudo:session): session closed for user root May 29 11:31:19 volumio volumio[1088]: verbose: New Socket.io Connection to 192.168.68.62 from 192.168.68.61 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 29 11:31:19 volumio volumio[1088]: info: CoreCommandRouter::volumioGetVisibleSources May 29 11:31:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 11:31:20 volumio sudo[2106]: pam_unix(sudo:session): session closed for user root May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 11:31:25 volumio volumio-remote-updater[701]: No test mode May 29 11:31:25 volumio volumio-remote-updater[701]: No alpha test mode May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 11:31:25 volumio volumio[1088]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 29 11:31:25 volumio volumio[1088]: info: Received Get System Version May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 11:31:25 volumio volumio[1088]: info: Received Get System Info May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 11:31:25 volumio volumio[1088]: info: Discovery: Getting this device information May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:31:25 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 11:31:25 volumio volumio[1088]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 11:31:25 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 11:31:26 volumio sudo[2165]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 11:31:26 volumio sudo[2165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:26 volumio sudo[2165]: pam_unix(sudo:session): session closed for user root May 29 11:31:26 volumio sudo[2167]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 11:31:26 volumio sudo[2167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:26 volumio sudo[2167]: pam_unix(sudo:session): session closed for user root May 29 11:31:26 volumio sudo[2170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 29 11:31:26 volumio sudo[2170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:26 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 29 11:31:26 volumio sudo[2170]: pam_unix(sudo:session): session closed for user root May 29 11:31:26 volumio volumio[1088]: info: Upmpdcli Daemon Started May 29 11:31:26 volumio upmpdcli[2188]: writing RSA key May 29 11:31:28 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 11:31:28 volumio volumio[1088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 29 11:31:28 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:31:28 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:33 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:31:33 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:31:33 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 29 11:31:33 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 29 11:31:33 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 29 11:31:33 volumio volumio[1088]: info: CoreCommandRouter::volumioGetBrowseSources May 29 11:31:33 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 11:31:34 volumio sudo[2208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 11:31:34 volumio sudo[2208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:34 volumio sudo[2210]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 11:31:34 volumio sudo[2210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:34 volumio sudo[2208]: pam_unix(sudo:session): session closed for user root May 29 11:31:34 volumio sudo[2210]: pam_unix(sudo:session): session closed for user root May 29 11:31:34 volumio volumio[1088]: verbose: New Socket.io Connection to 192.168.68.62 from 192.168.68.61 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::volumioGetVisibleSources May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 29 11:31:35 volumio volumio[1088]: info: Received Get System Info May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 11:31:35 volumio volumio[1088]: info: Discovery: Getting this device information May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState May 29 11:31:35 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 11:31:35 volumio volumio-remote-updater[701]: No test mode May 29 11:31:35 volumio volumio-remote-updater[701]: No alpha test mode May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Retrieving Cloud Streaming UI May 29 11:31:35 volumio volumio[1088]: info: Getting Tidal Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Getting Qobuz Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: Asking plugin for UI Config May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Getting Spotify Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: Asking plugin for UI Config May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Saving Spotify Acccount May 29 11:31:35 volumio volumio[1088]: info: Got Tidal Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: Got it May 29 11:31:35 volumio volumio[1088]: info: Got it May 29 11:31:35 volumio volumio[1088]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 29 11:31:35 volumio volumio[1088]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 29 11:31:35 volumio volumio[1088]: info: Completed starting MyVolumio Plugin May 29 11:31:35 volumio volumio[1088]: [Metrics] CommandRouter: 49s 848.31ms May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::volumiosetStartupVolume May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Retrieving Cloud Streaming UI May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Retrieving Cloud Streaming UI May 29 11:31:35 volumio volumio[1088]: info: Getting Tidal Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Getting Qobuz Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: Asking plugin for UI Config May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Getting Spotify Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: Asking plugin for UI Config May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Saving Spotify Acccount May 29 11:31:35 volumio volumio[1088]: info: Got Tidal Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: Got it May 29 11:31:35 volumio volumio[1088]: info: Got it May 29 11:31:35 volumio volumio[1088]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 29 11:31:35 volumio volumio[1088]: info: Getting Tidal Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Getting Qobuz Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: Asking plugin for UI Config May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Getting Spotify Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: Asking plugin for UI Config May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: info: Saving Spotify Acccount May 29 11:31:35 volumio volumio[1088]: info: Got Tidal Cloud Configuration May 29 11:31:35 volumio volumio[1088]: info: Got it May 29 11:31:35 volumio volumio[1088]: info: Got it May 29 11:31:35 volumio volumio[1088]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:31:35 volumio volumio[1088]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 29 11:31:35 volumio volumio[1088]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 11:31:35 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 11:31:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 29 11:31:36 volumio volumio[1088]: info: Loading i18n strings for locale en May 29 11:31:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 29 11:31:36 volumio volumio[1088]: info: Setting timezone to Asia/Calcutta May 29 11:31:36 volumio sudo[2223]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 29 11:31:36 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:36 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root May 29 11:31:36 volumio sudo[2227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Calcutta /etc/localtime May 29 11:31:36 volumio sudo[2227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:36 volumio sudo[2227]: pam_unix(sudo:session): session closed for user root May 29 11:31:36 volumio sudo[2231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 29 11:31:36 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:36 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root May 29 11:31:36 volumio sudo[2235]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Calcutta May 29 11:31:36 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:36 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root May 29 11:31:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 29 11:31:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 29 11:31:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 29 11:31:36 volumio volumio[1088]: info: Version has changed, forcing UI Reload May 29 11:31:36 volumio volumio[1088]: info: CoreCommandRouter::Reload Ui May 29 11:31:36 volumio volumio[1088]: Updating browse sources language May 29 11:31:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 11:31:36 volumio volumio[1088]: info: Fetching Streaming Services browse cache May 29 11:31:36 volumio sudo[2241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 11:31:36 volumio sudo[2241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:36 volumio sudo[2243]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 11:31:36 volumio sudo[2243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:36 volumio sudo[2243]: pam_unix(sudo:session): session closed for user root May 29 11:31:36 volumio sudo[2241]: pam_unix(sudo:session): session closed for user root May 29 11:31:37 volumio volumio[1088]: verbose: New Socket.io Connection to 192.168.68.62 from 192.168.68.61 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 29 11:31:37 volumio volumio[1088]: info: CoreCommandRouter::volumioGetVisibleSources May 29 11:31:37 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 11:31:38 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 11:31:39 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 29 11:31:39 volumio volumio[1088]: info: MyVolumio login type: Token May 29 11:31:39 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 11:31:40 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 29 11:31:40 volumio volumio[1088]: info: MyVolumio login type: Token May 29 11:31:40 volumio volumio[1088]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 29 11:31:40 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 29 11:31:40 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 11:31:41 volumio volumio-remote-updater[701]: No test mode May 29 11:31:41 volumio volumio-remote-updater[701]: No alpha test mode May 29 11:31:41 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 11:31:41 volumio volumio[1088]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 11:31:41 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 11:31:42 volumio volumio[1088]: info: BOOT COMPLETED May 29 11:31:42 volumio volumio[1088]: info: MyVolumio token set successfully May 29 11:31:42 volumio volumio[1088]: info: MYVOLUMIO: Adding device May 29 11:31:42 volumio volumio[1088]: info: MYVOLUMIO: Evaluating Server May 29 11:31:42 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 11:31:43 volumio volumio[1088]: info: MyVolumio status changed May 29 11:31:43 volumio volumio[1088]: info: Streaming services startup May 29 11:31:43 volumio volumio[1088]: info: Starting Streaming Daemon May 29 11:31:43 volumio volumio[1088]: info: Removing browser output: myVolumio user plan is not superstar May 29 11:31:43 volumio volumio[1088]: info: Removing audio output: May 29 11:31:43 volumio volumio[1088]: info: Stoppping Tunnel 1 May 29 11:31:43 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 11:31:43 volumio sudo[2279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 11:31:43 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:43 volumio sudo[2281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 29 11:31:43 volumio sudo[2281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 11:31:43 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 11:31:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:31:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:31:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:31:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:31:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:31:43 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 11:31:43 volumio sudo[2281]: pam_unix(sudo:session): session closed for user root May 29 11:31:43 volumio setdatetime-helper.sh[2284]: Time is not synchronized. Attempting to sync... May 29 11:31:43 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root May 29 11:31:43 volumio volumio[1088]: error: Cannot start Volumio Streaming Daemon May 29 11:31:43 volumio volumio[1088]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 11:31:43 volumio volumio[1088]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 11:31:43 volumio volumio[1088]: info: Remote SSH Stopped May 29 11:31:43 volumio sudo[2292]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 29 May 2025 06:01:43 GMT#015' May 29 11:31:43 volumio sudo[2292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 11:31:43 volumio setdatetime-helper.sh[2293]: Thu May 29 11:31:43 IST 2025 May 29 11:31:43 volumio setdatetime-helper.sh[2284]: Time synchronized successfully. May 29 11:31:43 volumio sudo[2292]: pam_unix(sudo:session): session closed for user root May 29 11:31:43 volumio systemd-journald[379]: Time jumped backwards, rotating. May 29 11:31:43 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 11:31:43 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 11:31:43 volumio volumio[1088]: error: Failed to ping endpoint eu3.myvolumio.org : unknown error May 29 11:31:43 volumio volumio[1088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 11:31:43 volumio volumio[1088]: Error: Unable to resolve or reject the same promise twice May 29 11:31:43 volumio volumio[1088]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 29 11:31:43 volumio volumio[1088]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32090) May 29 11:31:43 volumio volumio[1088]: at Socket.emit (node:events:526:35) May 29 11:31:43 volumio volumio[1088]: at endReadableNT (node:internal/streams/readable:1376:12) May 29 11:31:43 volumio volumio[1088]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) May 29 11:31:43 volumio volumio[1088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 11:31:44 volumio sudo[2309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-29 11:30' May 29 11:31:44 volumio sudo[2309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="fd567ba9bc0dc34c0403e2d5b72a0f46467d1983" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri May 23 08:02:06 UTC 2025" VOLUMIO_VERSION="0.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="76fa67e30b28261e1dc7c3a89e610b9e"