-- Logs begin at Thu 2025-05-22 17:35:48 CEST, end at Thu 2025-05-22 17:37:26 CEST. -- May 22 17:36:00 volumio volumio[862]: info: Plugin folders cleanup May 22 17:36:00 volumio volumio[862]: info: Scanning into folder /volumio/app/plugins/ May 22 17:36:00 volumio volumio[862]: info: Scanning category audio_interface May 22 17:36:00 volumio volumio[862]: info: Scanning category miscellanea May 22 17:36:00 volumio volumio[862]: info: Scanning category music_service May 22 17:36:00 volumio volumio[862]: info: Scanning category plugins.json May 22 17:36:00 volumio volumio[862]: info: Scanning category system_controller May 22 17:36:00 volumio volumio[862]: info: Scanning category user_interface May 22 17:36:00 volumio volumio[862]: info: Scanning into folder /data/plugins/ May 22 17:36:00 volumio volumio[862]: info: Plugin folders cleanup completed May 22 17:36:00 volumio volumio[862]: info: ------------------------------------------- May 22 17:36:00 volumio volumio[862]: info: ----- Core plugins startup ---- May 22 17:36:00 volumio volumio[862]: info: ------------------------------------------- May 22 17:36:00 volumio volumio[862]: info: Loading plugins from folder /volumio/app/plugins/ May 22 17:36:00 volumio volumio[862]: info: Adding plugin upnp to MyMusic Plugins May 22 17:36:00 volumio volumio[862]: info: Adding plugin airplay_emulation to MyMusic Plugins May 22 17:36:00 volumio volumio[862]: info: Adding plugin upnp_browser to MyMusic Plugins May 22 17:36:00 volumio volumio[862]: info: Loading plugins from folder /data/plugins/ May 22 17:36:00 volumio volumio[862]: info: Loading plugin "system"... May 22 17:36:00 volumio volumio[862]: info: Loading plugin "appearance"... May 22 17:36:01 volumio volumio[862]: info: Loading plugin "network"... May 22 17:36:01 volumio volumio[862]: info: Refreshing Cached IP Addresses May 22 17:36:01 volumio sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:36:01 volumio sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:01 volumio sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:36:01 volumio sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:01 volumio sudo[1107]: pam_unix(sudo:session): session closed for user root May 22 17:36:01 volumio sudo[1109]: pam_unix(sudo:session): session closed for user root May 22 17:36:01 volumio volumio[862]: info: Loading plugin "services"... May 22 17:36:01 volumio sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 22 17:36:01 volumio sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:01 volumio volumio[862]: info: Loading plugin "alsa_controller"... May 22 17:36:01 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root May 22 17:36:01 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:36:01 volumio volumio[862]: info: Loading plugin "wizard"... May 22 17:36:01 volumio volumio[862]: info: Loading plugin "networkfs"... May 22 17:36:01 volumio volumio[862]: info: Starting Udev Watcher for removable devices May 22 17:36:01 volumio volumio[862]: info: Mounting Device EFI May 22 17:36:01 volumio sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 22 17:36:01 volumio sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:01 volumio sudo[1148]: pam_unix(sudo:session): session closed for user root May 22 17:36:01 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 22 17:36:01 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 22 17:36:01 volumio volumio[862]: info: Mounting Device PenDrive May 22 17:36:01 volumio sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/PenDrive -o noatime May 22 17:36:01 volumio sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:01 volumio dbus-daemon[663]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.6' (uid=0 pid=1077 comm="/usr/lib/chromium/chromium --show-component-extens") May 22 17:36:01 volumio systemd[1]: Starting Bluetooth service... May 22 17:36:01 volumio mount.exfat[1158]: volume was not unmounted cleanly May 22 17:36:01 volumio sudo[1154]: pam_unix(sudo:session): session closed for user root May 22 17:36:01 volumio volumio[862]: WARN: volume was not unmounted cleanly. May 22 17:36:01 volumio volumio[862]: info: Ignoring mount for partition: boot May 22 17:36:02 volumio volumio[862]: info: Ignoring mount for partition: volumio May 22 17:36:02 volumio volumio[862]: info: Ignoring mount for partition: volumio_data May 22 17:36:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:36:02 volumio volumio[862]: info: Loading plugin "volumio_command_line_client"... May 22 17:36:02 volumio volumio[862]: info: Loading plugin "upnp"... May 22 17:36:02 volumio volumio[862]: info: [1747928162014] Starting Upmpd Daemon May 22 17:36:02 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:36:02 volumio volumio[862]: info: Loading plugin "my_music"... May 22 17:36:02 volumio volumio[862]: info: Loading plugin "mpd"... May 22 17:36:02 volumio kernel: cryptd: max_cpu_qlen set to 1000 May 22 17:36:02 volumio bluetoothd[1159]: Bluetooth daemon 5.50 May 22 17:36:02 volumio kernel: Bluetooth: Core ver 2.22 May 22 17:36:02 volumio kernel: NET: Registered PF_BLUETOOTH protocol family May 22 17:36:02 volumio kernel: Bluetooth: HCI device and connection manager initialized May 22 17:36:02 volumio kernel: Bluetooth: HCI socket layer initialized May 22 17:36:02 volumio kernel: Bluetooth: L2CAP socket layer initialized May 22 17:36:02 volumio kernel: Bluetooth: SCO socket layer initialized May 22 17:36:02 volumio bluetoothd[1159]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 22 17:36:02 volumio dbus-daemon[663]: [system] Successfully activated service 'org.bluez' May 22 17:36:02 volumio systemd[1]: Started Bluetooth service. May 22 17:36:02 volumio volumio[862]: info: Loading plugin "upnp_browser"... May 22 17:36:02 volumio bluetoothd[1159]: Starting SDP server May 22 17:36:02 volumio bluetoothd[1159]: Excluding (cli) sap May 22 17:36:02 volumio bluetoothd[1159]: Bluetooth management interface 1.22 initialized May 22 17:36:02 volumio dbus-daemon[663]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.7' (uid=0 pid=1159 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 22 17:36:02 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 22 17:36:02 volumio kernel: Bluetooth: BNEP filters: protocol multicast May 22 17:36:02 volumio kernel: Bluetooth: BNEP socket layer initialized May 22 17:36:02 volumio systemd[1]: Starting Hostname Service... May 22 17:36:02 volumio dbus-daemon[663]: [system] Successfully activated service 'org.freedesktop.hostname1' May 22 17:36:02 volumio systemd[1]: Started Hostname Service. May 22 17:36:03 volumio dhcpcd[755]: eth0: probing for an IPv4LL address May 22 17:36:03 volumio sh[609]: eth0: probing for an IPv4LL address May 22 17:36:03 volumio dhcpcd[680]: eth0: probing for an IPv4LL address May 22 17:36:03 volumio sh[609]: eth0: DHCP lease expired May 22 17:36:03 volumio dhcpcd[755]: eth0: DHCP lease expired May 22 17:36:03 volumio dhcpcd[680]: eth0: DHCP lease expired May 22 17:36:03 volumio sh[609]: eth0: soliciting a DHCP lease May 22 17:36:03 volumio dhcpcd[680]: eth0: soliciting a DHCP lease May 22 17:36:03 volumio dhcpcd[755]: eth0: soliciting a DHCP lease May 22 17:36:03 volumio volumio[862]: info: Loading plugin "alarm-clock"... May 22 17:36:03 volumio volumio-time-update[638]: volumio-time-update-util: Fetching time from Volumio... May 22 17:36:03 volumio volumio-time-update[638]: volumio-time-update-util: Date not found in response May 22 17:36:03 volumio volumio-time-update[638]: volumio-time-update-util: Retrying in 5 seconds... May 22 17:36:03 volumio volumio[862]: info: Loading plugin "airplay_emulation"... May 22 17:36:03 volumio volumio[862]: info: Starting Shairport Sync May 22 17:36:03 volumio volumio[862]: info: Loading plugin "last_100"... May 22 17:36:03 volumio volumio[862]: info: Loading plugin "webradio"... May 22 17:36:03 volumio volumio[862]: info: Loading plugin "i2s_dacs"... May 22 17:36:03 volumio volumio[862]: info: I2S DAC not set, start Auto-detection May 22 17:36:03 volumio volumio[862]: info: Loading plugin "volumiodiscovery"... May 22 17:36:03 volumio volumio[862]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:36:03 volumio volumio[862]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:36:03 volumio volumio[862]: *** WARNING *** For more information see May 22 17:36:03 volumio volumio[862]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:36:03 volumio volumio[862]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:36:03 volumio node[862]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 22 17:36:03 volumio volumio[862]: *** WARNING *** For more information see May 22 17:36:03 volumio node[862]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:36:03 volumio node[862]: *** WARNING *** For more information see May 22 17:36:03 volumio node[862]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 22 17:36:03 volumio node[862]: *** WARNING *** Please fix your application to use the native API of Avahi! May 22 17:36:03 volumio node[862]: *** WARNING *** For more information see May 22 17:36:03 volumio volumio[862]: info: Applying required configuration parameters for plugin volumiodiscovery May 22 17:36:03 volumio volumio[862]: info: Discovery: Started advertising with name: Volumio May 22 17:36:03 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 22 17:36:03 volumio volumio[862]: info: Loading plugin "outputs"... May 22 17:36:03 volumio volumio[862]: info: Loading plugin "albumart"... May 22 17:36:03 volumio volumio[862]: info: Plugin example_plugin is not enabled May 22 17:36:03 volumio volumio[862]: info: Loading plugin "inputs"... May 22 17:36:03 volumio volumio[862]: info: Loading plugin "updater_comm"... May 22 17:36:03 volumio volumio[862]: Forking 1 albumart workers May 22 17:36:03 volumio volumio[862]: info: Plugin mpdemulation is not enabled May 22 17:36:03 volumio volumio[862]: info: Loading plugin "rest_api"... May 22 17:36:03 volumio volumio[862]: info: Loading plugin "websocket"... May 22 17:36:03 volumio volumio[862]: info: Starting Socket.io Server version 2.3.0 May 22 17:36:03 volumio volumio[862]: info: Loading i18n strings for locale it May 22 17:36:03 volumio volumio[862]: Updating browse sources language May 22 17:36:03 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:36:03 volumio volumio[862]: Starting albumart workers May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::initPlayerControls May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:36:04 volumio volumio[862]: Express server listening on port 3000 May 22 17:36:04 volumio volumio[862]: [Metrics] WebUI: 6s 665.55ms May 22 17:36:04 volumio volumio[862]: info: Setting Device type: x86 May 22 17:36:04 volumio volumio[862]: info: CoreStateMachine::resetVolumioState May 22 17:36:04 volumio volumio[862]: info: CoreStateMachine::getcurrentVolume May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:04 volumio volumio[862]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 22 17:36:04 volumio volumio[862]: info: Completed loading Core Plugins May 22 17:36:04 volumio volumio[862]: info: Preparing to generate the ALSA configuration file May 22 17:36:04 volumio volumio[862]: info: Volumio Network Manager: Network status updated: 0 May 22 17:36:04 volumio volumio[862]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 22 17:36:04 volumio volumio[862]: wlan0 Interface doesn't support scanning. May 22 17:36:04 volumio volumio[862]: info: Cannot use regular scanning, forcing with ap-force May 22 17:36:04 volumio sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 22 17:36:04 volumio sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:04 volumio sudo[1318]: pam_unix(sudo:session): session closed for user root May 22 17:36:04 volumio volumio[862]: command failed: No such device (-19) May 22 17:36:04 volumio volumio[862]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 22 17:36:04 volumio volumio[862]: command failed: No such device (-19) May 22 17:36:04 volumio volumio[862]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 22 17:36:04 volumio volumio[862]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 22 17:36:04 volumio volumio[862]: info: Reading ALSA contributions from plugins. May 22 17:36:04 volumio volumio[862]: info: CoreStateMachine::pushState May 22 17:36:04 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::volumioPushState May 22 17:36:04 volumio volumio[862]: info: CoreStateMachine::updateTrackBlock May 22 17:36:04 volumio volumio[862]: info: CorePlayQueue::getTrackBlock May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::volumioRetrievevolume May 22 17:36:04 volumio volumio[862]: info: Reloading queue from file May 22 17:36:04 volumio volumio[862]: info: CoreStateMachine::pushState May 22 17:36:04 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::volumioPushState May 22 17:36:04 volumio volumio[862]: info: CoreStateMachine::setRepeat null single undefined May 22 17:36:04 volumio volumio[862]: info: CoreStateMachine::pushState May 22 17:36:04 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::volumioPushState May 22 17:36:04 volumio volumio[862]: info: CoreStateMachine::setRandom null May 22 17:36:04 volumio volumio[862]: info: CoreStateMachine::pushState May 22 17:36:04 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::volumioPushState May 22 17:36:04 volumio volumio[862]: info: Asound.conf file unchanged, so no further update is needed May 22 17:36:04 volumio volumio[862]: info: Output device has changed, restarting MPD May 22 17:36:04 volumio volumio[862]: info: Output device has changed, restarting Shairport Sync May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:04 volumio volumio[862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:36:04 volumio volumio[862]: info: ___________ START PLUGINS ___________ May 22 17:36:04 volumio volumio[862]: info: ControllerMpd::onStart: Initializing MPD May 22 17:36:04 volumio volumio[862]: info: Creating MPD Configuration file May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:36:04 volumio volumio[862]: info: [1747928164252] CoreMusicLibrary::Adding element Server multimediali May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:04 volumio sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:36:04 volumio sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:04 volumio sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:36:04 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 22 17:36:04 volumio sudo[1341]: pam_unix(sudo:session): session closed for user root May 22 17:36:04 volumio sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:04 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:04 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root May 22 17:36:04 volumio systemd[1]: Stopping Music Player Daemon... May 22 17:36:04 volumio sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 22 17:36:04 volumio sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:04 volumio volumio[862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:04 volumio volumio[862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:36:04 volumio volumio[862]: info: [1747928164299] CoreMusicLibrary::Adding element Last_100 May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 22 17:36:04 volumio volumio[862]: info: [1747928164299] CoreMusicLibrary::Adding element Webradio May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:36:04 volumio volumio[862]: info: Initializing BBC Radios May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:04 volumio volumio[862]: info: Volumio Calling Home May 22 17:36:04 volumio volumio[862]: Unhandled rejection Error: No sockets available, cannot start. May 22 17:36:04 volumio volumio[862]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) May 22 17:36:04 volumio volumio[862]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) May 22 17:36:04 volumio volumio[862]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 May 22 17:36:04 volumio volumio[862]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) May 22 17:36:04 volumio volumio[862]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) May 22 17:36:04 volumio volumio[862]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) May 22 17:36:04 volumio volumio[862]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) May 22 17:36:04 volumio volumio[862]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) May 22 17:36:04 volumio volumio[862]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) May 22 17:36:04 volumio volumio[862]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) May 22 17:36:04 volumio volumio[862]: at HashMap. (/volumio/app/pluginmanager.js:496:31) May 22 17:36:04 volumio volumio[862]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 22 17:36:04 volumio volumio[862]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 22 17:36:04 volumio volumio[862]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) May 22 17:36:04 volumio volumio[862]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) May 22 17:36:04 volumio volumio[862]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 22 17:36:04 volumio volumio[862]: info: MPD Permissions set May 22 17:36:04 volumio systemd[1]: mpd.service: Succeeded. May 22 17:36:04 volumio systemd[1]: Stopped Music Player Daemon. May 22 17:36:04 volumio volumio[862]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio systemd[1]: Starting Music Player Daemon... May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio sudo[1374]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 22 17:36:04 volumio sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:04 volumio sudo[1374]: pam_unix(sudo:session): session closed for user root May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:04 volumio volumio[862]: info: Starting Shairport Sync May 22 17:36:04 volumio volumio[862]: info: Starting Shairport Sync May 22 17:36:04 volumio volumio[862]: info: Starting Shairport Sync May 22 17:36:04 volumio sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:36:04 volumio sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:36:04 volumio sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:04 volumio sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:36:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 22 17:36:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:36:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:36:04 volumio sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 22 17:36:04 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:04 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:04 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 22 17:36:04 volumio sudo[1392]: pam_unix(sudo:session): session closed for user root May 22 17:36:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 22 17:36:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 22 17:36:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 22 17:36:04 volumio volumio[862]: info: Shairport-Sync Started May 22 17:36:04 volumio volumio[862]: Error adding Membership: Error: addMembership EINVAL May 22 17:36:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 22 17:36:04 volumio sudo[1390]: pam_unix(sudo:session): session closed for user root May 22 17:36:04 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root May 22 17:36:04 volumio volumio[862]: info: Shairport-Sync Started May 22 17:36:04 volumio volumio[862]: info: Shairport-Sync Started May 22 17:36:04 volumio mpd[1382]: May 22 17:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 22 17:36:04 volumio systemd[1]: Started Music Player Daemon. May 22 17:36:04 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root May 22 17:36:04 volumio sudo[1343]: pam_unix(sudo:session): session closed for user root May 22 17:36:04 volumio volumio[862]: info: Completed starting Core Plugins May 22 17:36:04 volumio volumio[862]: info: ------------------------------------------- May 22 17:36:04 volumio volumio[862]: info: ----- MyVolumio plugins startup ---- May 22 17:36:04 volumio volumio[862]: info: ------------------------------------------- May 22 17:36:04 volumio volumio[862]: info: [MyVolumio PluginManager] Fetching plans data.... May 22 17:36:04 volumio volumio[862]: info: MPD running with PID1382 May 22 17:36:04 volumio volumio[862]: ,establishing connection May 22 17:36:04 volumio volumio[862]: error: MPD error: The expression evaluated to a falsy value: May 22 17:36:04 volumio volumio[862]: assert.ok(self.idling) May 22 17:36:04 volumio volumio[862]: error: The expression evaluated to a falsy value: May 22 17:36:04 volumio volumio[862]: assert.ok(self.idling) May 22 17:36:04 volumio volumio[862]: error: MPD error: The expression evaluated to a falsy value: May 22 17:36:04 volumio volumio[862]: assert.ok(self.idling) May 22 17:36:04 volumio volumio[862]: error: The expression evaluated to a falsy value: May 22 17:36:04 volumio volumio[862]: assert.ok(self.idling) May 22 17:36:04 volumio volumio[862]: error: updateQueue error: null May 22 17:36:05 volumio volumio[862]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:36:05 volumio volumio[862]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::volumioGetVisibleSources May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:05 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue May 22 17:36:05 volumio volumio[862]: info: CoreStateMachine::getQueue May 22 17:36:05 volumio volumio[862]: info: CorePlayQueue::getQueue May 22 17:36:05 volumio volumio[862]: info: Listing playlists May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 17:36:05 volumio volumio[862]: info: Received Get System Info May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:36:05 volumio volumio[862]: info: Discovery: Getting this device information May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:05 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:36:05 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:05 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:06 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 22 17:36:06 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 17:36:07 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 22 17:36:07 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 17:36:07 volumio volumio[862]: info: Received Get System Info May 22 17:36:07 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:36:07 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:36:07 volumio volumio[862]: info: Discovery: Getting this device information May 22 17:36:07 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:07 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:07 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:36:07 volumio dhcpcd[755]: eth0: using IPv4LL address 169.254.166.224 May 22 17:36:07 volumio dhcpcd[755]: eth0: adding route to 169.254.0.0/16 May 22 17:36:07 volumio avahi-daemon[653]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.166.224. May 22 17:36:07 volumio dhcpcd[755]: eth0: adding default route May 22 17:36:07 volumio avahi-daemon[653]: New relevant interface eth0.IPv4 for mDNS. May 22 17:36:07 volumio avahi-daemon[653]: Registering new address record for 169.254.166.224 on eth0.IPv4. May 22 17:36:08 volumio volumio-time-update[638]: volumio-time-update-util: Fetching time from Volumio... May 22 17:36:08 volumio sh[609]: eth0: using IPv4LL address 169.254.166.224 May 22 17:36:08 volumio dhcpcd[680]: eth0: using IPv4LL address 169.254.166.224 May 22 17:36:08 volumio sh[609]: eth0: adding route to 169.254.0.0/16 May 22 17:36:08 volumio sh[609]: eth0: adding default route May 22 17:36:08 volumio dhcpcd[680]: eth0: adding route to 169.254.0.0/16 May 22 17:36:08 volumio dhcpcd[680]: eth0: adding default route May 22 17:36:08 volumio sh[609]: forked to background, child pid 1431 May 22 17:36:08 volumio dhcpcd[680]: forked to background, child pid 1431 May 22 17:36:08 volumio ifplugd(eth0)[810]: client: ifup: interface eth0 already configured May 22 17:36:08 volumio sh[609]: eth0=eth0 May 22 17:36:08 volumio ifplugd(eth0)[810]: Program executed successfully. May 22 17:36:08 volumio ntpd[841]: Listen normally on 3 eth0 169.254.166.224:123 May 22 17:36:08 volumio ntpd[841]: new interface(s) found: waking up resolver May 22 17:36:10 volumio nmbd[784]: [2025/05/22 17:36:10.002278, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 22 17:36:10 volumio systemd[1]: Started Samba NMB Daemon. May 22 17:36:10 volumio nmbd[784]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 22 17:36:10 volumio systemd[1]: Starting Samba Winbind Daemon... May 22 17:36:10 volumio winbindd[1458]: [2025/05/22 17:36:10.148241, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 22 17:36:10 volumio winbindd[1458]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 22 17:36:10 volumio winbindd[1458]: [2025/05/22 17:36:10.151066, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 22 17:36:10 volumio systemd[1]: Started Samba Winbind Daemon. May 22 17:36:10 volumio winbindd[1458]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 22 17:36:10 volumio systemd[1]: Starting Samba SMB Daemon... May 22 17:36:10 volumio smbd[1463]: [2025/05/22 17:36:10.544762, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 22 17:36:10 volumio systemd[1]: Started Samba SMB Daemon. May 22 17:36:10 volumio smbd[1463]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 22 17:36:10 volumio volumio-remote-updater[644]: [2025-05-22 17:36:10] [connect] Successful connection May 22 17:36:10 volumio volumio-remote-updater[644]: [2025-05-22 17:36:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747928170 101 May 22 17:36:10 volumio volumio[862]: 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: 4 May 22 17:36:11 volumio volumio[862]: info: Volumio Network Manager: Network status updated: 1 May 22 17:36:12 volumio sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 22 17:36:12 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:12 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root May 22 17:36:12 volumio sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 22 17:36:12 volumio sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:12 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root May 22 17:36:12 volumio sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 22 17:36:12 volumio sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:12 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 22 17:36:12 volumio sudo[1494]: pam_unix(sudo:session): session closed for user root May 22 17:36:12 volumio volumio[862]: info: Upmpdcli Daemon Started May 22 17:36:12 volumio volumio[1496]: Generating RSA private key, 4096 bit long modulus (2 primes) May 22 17:36:12 volumio volumio[1496]: .........++++ May 22 17:36:12 volumio volumio[1496]: ....++++ May 22 17:36:12 volumio volumio[1496]: e is 65537 (0x010001) May 22 17:36:12 volumio volumio[1496]: writing RSA key May 22 17:36:14 volumio volumio[862]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.812&uuid=d6b17d201db73150280d77250758cea7" http://updates.volumio.org/downloader-v1/track-device May 22 17:36:14 volumio volumio[862]: % Total % Received % Xferd Average Speed Time Time Time Current May 22 17:36:14 volumio volumio[862]: Dload Upload Total Spent Left Speed May 22 17:36:14 volumio volumio[862]: [132B blob data] May 22 17:36:14 volumio volumio[862]: retrying in 5 seconds, trial 0 May 22 17:36:14 volumio volumio[862]: info: Volumio Calling Home May 22 17:36:15 volumio dhcpcd[755]: eth0: offered 192.168.8.194 from 192.168.8.1 May 22 17:36:15 volumio dhcpcd[1431]: eth0: offered 192.168.8.194 from 192.168.8.1 May 22 17:36:15 volumio dhcpcd[755]: eth0: probing address 192.168.8.194/24 May 22 17:36:15 volumio dhcpcd[1431]: eth0: probing address 192.168.8.194/24 May 22 17:36:15 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 22 17:36:20 volumio dhcpcd[755]: eth0: leased 192.168.8.194 for 86400 seconds May 22 17:36:20 volumio dhcpcd[755]: eth0: adding route to 192.168.8.0/24 May 22 17:36:20 volumio dhcpcd[755]: eth0: changing default route via 192.168.8.1 May 22 17:36:20 volumio dhcpcd[755]: eth0: dhcp_envoption 213: Operation not supported May 22 17:36:20 volumio dhcpcd[755]: eth0: dhcp_envoption 213: Operation not supported May 22 17:36:20 volumio avahi-daemon[653]: Registering new address record for 192.168.8.194 on eth0.IPv4. May 22 17:36:20 volumio dhcpcd[1431]: eth0: deleted default route May 22 17:36:20 volumio ntpd[841]: ntpd exiting on signal 15 (Terminated) May 22 17:36:20 volumio systemd[1]: Stopping Network Time Service... May 22 17:36:20 volumio systemd[1]: ntp.service: Succeeded. May 22 17:36:20 volumio systemd[1]: Stopped Network Time Service. May 22 17:36:20 volumio systemd[1]: Starting Network Time Service... May 22 17:36:20 volumio ntpd[1751]: ntpd 4.2.8p12@1.3728-o (1): Starting May 22 17:36:20 volumio ntpd[1751]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 22 17:36:20 volumio systemd[1]: Started Network Time Service. May 22 17:36:20 volumio ntpd[1757]: proto: precision = 0.049 usec (-24) May 22 17:36:20 volumio ntpd[1757]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 22 17:36:20 volumio ntpd[1757]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 22 17:36:20 volumio ntpd[1757]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 877 days ago May 22 17:36:20 volumio ntpd[1757]: Listen and drop on 0 v6wildcard [::]:123 May 22 17:36:20 volumio ntpd[1757]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 22 17:36:20 volumio ntpd[1757]: Listen normally on 2 lo 127.0.0.1:123 May 22 17:36:20 volumio ntpd[1757]: Listen normally on 3 eth0 169.254.166.224:123 May 22 17:36:20 volumio ntpd[1757]: Listen normally on 4 eth0 192.168.8.194:123 May 22 17:36:20 volumio ntpd[1757]: Listening on routing socket on fd #21 for interface updates May 22 17:36:20 volumio ntpd[1757]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 22 17:36:20 volumio ntpd[1757]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 22 17:36:20 volumio avahi-daemon[653]: Withdrawing address record for 169.254.166.224 on eth0. May 22 17:36:20 volumio avahi-daemon[653]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.166.224. May 22 17:36:20 volumio dhcpcd[755]: eth0: deleting route to 169.254.0.0/16 May 22 17:36:20 volumio dhcpcd[1431]: eth0: deleted route to 169.254.0.0/16 May 22 17:36:20 volumio avahi-daemon[653]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.8.194. May 22 17:36:20 volumio dhcpcd[1431]: eth0: adding route to 169.254.0.0/16 May 22 17:36:20 volumio dhcpcd[1431]: if_route (ADD): Invalid argument May 22 17:36:20 volumio dhcpcd[1431]: eth0: adding default route May 22 17:36:20 volumio dhcpcd[1431]: if_route (ADD): Invalid argument May 22 17:36:20 volumio dhcpcd[1431]: eth0: leased 192.168.8.194 for 86400 seconds May 22 17:36:20 volumio dhcpcd[1431]: eth0: adding route to 192.168.8.0/24 May 22 17:36:20 volumio dhcpcd[1431]: eth0: adding default route via 192.168.8.1 May 22 17:36:20 volumio dhcpcd[1431]: eth0: adding route to 169.254.0.0/16 May 22 17:36:20 volumio dhcpcd[1431]: if_route (ADD): Invalid argument May 22 17:36:20 volumio dhcpcd[1431]: eth0: dhcp_envoption 213: Operation not supported May 22 17:36:20 volumio dhcpcd[1431]: eth0: dhcp_envoption 213: Operation not supported May 22 17:36:20 volumio ntpd[1757]: ntpd exiting on signal 15 (Terminated) May 22 17:36:20 volumio systemd[1]: Stopping Network Time Service... May 22 17:36:20 volumio systemd[1]: ntp.service: Succeeded. May 22 17:36:20 volumio systemd[1]: Stopped Network Time Service. May 22 17:36:20 volumio systemd[1]: Starting Network Time Service... May 22 17:36:20 volumio ntpd[1805]: ntpd 4.2.8p12@1.3728-o (1): Starting May 22 17:36:20 volumio ntpd[1805]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 22 17:36:20 volumio systemd[1]: Started Network Time Service. May 22 17:36:20 volumio ntpd[1811]: proto: precision = 0.050 usec (-24) May 22 17:36:20 volumio ntpd[1811]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 22 17:36:20 volumio ntpd[1811]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 22 17:36:20 volumio ntpd[1811]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 877 days ago May 22 17:36:20 volumio ntpd[1811]: Listen and drop on 0 v6wildcard [::]:123 May 22 17:36:20 volumio ntpd[1811]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 22 17:36:20 volumio ntpd[1811]: Listen normally on 2 lo 127.0.0.1:123 May 22 17:36:20 volumio ntpd[1811]: Listen normally on 3 eth0 192.168.8.194:123 May 22 17:36:20 volumio ntpd[1811]: Listening on routing socket on fd #20 for interface updates May 22 17:36:20 volumio ntpd[1811]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 22 17:36:20 volumio ntpd[1811]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 22 17:36:20 volumio kernel: dhcpcd[1431]: segfault at e0 ip 0000556905130075 sp 00007fff272a6330 error 4 in dhcpcd[55690511b000+32000] likely on CPU 1 (core 1, socket 0) May 22 17:36:20 volumio kernel: Code: 00 00 48 89 44 24 78 31 c0 48 8d 54 24 14 31 c0 66 89 4c 24 14 48 c7 44 24 16 00 00 00 00 66 89 42 0a 85 ed 0f 85 d3 00 00 00 <48> 8b 87 e0 00 00 00 44 8b 20 8b 03 66 0f ef c0 48 8d 74 24 20 66 May 22 17:36:21 volumio ntpd[1811]: Soliciting pool server 85.199.214.99 May 22 17:36:22 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 22 17:36:22 volumio ntpd[1811]: Soliciting pool server 89.46.74.148 May 22 17:36:23 volumio ntpd[1811]: Soliciting pool server 151.22.209.90 May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 22 17:36:23 volumio volumio[862]: info: Adding plugin bluetooth to MyMusic Plugins May 22 17:36:23 volumio volumio[862]: info: Adding plugin multiroom to MyMusic Plugins May 22 17:36:23 volumio volumio[862]: info: Adding plugin metavolumio to MyMusic Plugins May 22 17:36:23 volumio volumio-time-update[638]: volumio-time-update-util: Date not found in response May 22 17:36:23 volumio volumio-time-update[638]: volumio-time-update-util: Retrying in 5 seconds... May 22 17:36:23 volumio volumio[862]: info: Adding plugin cd_controller to MyMusic Plugins May 22 17:36:23 volumio volumio[862]: info: Adding plugin qobuzconnect to MyMusic Plugins May 22 17:36:23 volumio volumio[862]: info: Adding plugin smart_inputs to MyMusic Plugins May 22 17:36:23 volumio volumio[862]: info: Adding plugin tidalconnect to MyMusic Plugins May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 22 17:36:23 volumio ntpd[1811]: Soliciting pool server 93.94.88.51 May 22 17:36:23 volumio ntpd[1811]: Soliciting pool server 212.45.144.206 May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 22 17:36:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:23 volumio volumio[862]: info: Starting MyVolumio Remote Streaming Endpoints May 22 17:36:23 volumio volumio[862]: info: MyVolumio login type: Token May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 22 17:36:23 volumio volumio[862]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 22 17:36:24 volumio ntpd[1811]: Soliciting pool server 162.159.200.123 May 22 17:36:24 volumio volumio[862]: info: Starting Streaming Service Transparent Proxy May 22 17:36:24 volumio volumio[862]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 22 17:36:24 volumio volumio[862]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 22 17:36:24 volumio volumio[862]: info: Streaming services startup May 22 17:36:24 volumio volumio[862]: info: Starting Streaming Daemon May 22 17:36:24 volumio sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 22 17:36:24 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:24 volumio volumio[862]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 22 17:36:24 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root May 22 17:36:24 volumio volumio[862]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 May 22 17:36:24 volumio volumio[862]: error: Cannot start Volumio Streaming Daemon May 22 17:36:24 volumio volumio[862]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 22 17:36:24 volumio volumio[862]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 22 17:36:24 volumio volumio[862]: verbose: New Socket.io Connection to 192.168.8.194:3000 from 192.168.8.184 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 22 17:36:24 volumio volumio[862]: STREAMING PROXY: Starting server on port 3245 May 22 17:36:24 volumio volumio[862]: Node JS runtime: 14 May 22 17:36:24 volumio volumio[862]: verbose: New Socket.io Connection to 192.168.8.194 from 192.168.8.184 UA: Mozilla/5.0 (Linux; Android 15; A015 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 22 17:36:24 volumio volumio[862]: info: Volumio called home May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetVisibleSources May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:24 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 22 17:36:24 volumio volumio[862]: info: Received Get System Info May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:36:24 volumio volumio[862]: info: Discovery: Getting this device information May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:24 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:24 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:24 volumio volumio[862]: info: Listing playlists May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue May 22 17:36:24 volumio volumio[862]: info: CoreStateMachine::getQueue May 22 17:36:24 volumio volumio[862]: info: CorePlayQueue::getQueue May 22 17:36:24 volumio ntpd[1811]: Soliciting pool server 95.110.254.234 May 22 17:36:24 volumio ntpd[1811]: Soliciting pool server 212.45.144.88 May 22 17:36:24 volumio ntpd[1811]: Soliciting pool server 37.247.53.178 May 22 17:36:24 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 22 17:36:25 volumio volumio[862]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 22 17:36:25 volumio ntpd[1811]: Soliciting pool server 185.157.229.254 May 22 17:36:25 volumio ntpd[1811]: Soliciting pool server 162.159.200.1 May 22 17:36:25 volumio ntpd[1811]: Soliciting pool server 81.56.230.156 May 22 17:36:25 volumio ntpd[1811]: Soliciting pool server 31.14.133.122 May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 17:36:26 volumio volumio[862]: info: Received Get System Info May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:36:26 volumio volumio[862]: info: Discovery: Getting this device information May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:26 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:36:26 volumio volumio[862]: info: Discovery: Getting this device information May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:26 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:36:26 volumio volumio[862]: verbose: New Socket.io Connection to 192.168.8.194:3000 from 192.168.8.184 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:26 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 22 17:36:26 volumio volumio[862]: info: MyVolumio token set successfully May 22 17:36:26 volumio volumio[862]: info: MYVOLUMIO: Adding device May 22 17:36:26 volumio volumio[862]: info: MYVOLUMIO: Evaluating Server May 22 17:36:26 volumio volumio[862]: info: MyVolumio status changed May 22 17:36:26 volumio volumio[862]: info: Streaming services startup May 22 17:36:26 volumio volumio[862]: info: Starting Streaming Daemon May 22 17:36:26 volumio volumio[862]: info: Removing browser output: myVolumio user plan is not superstar May 22 17:36:26 volumio volumio[862]: info: Removing audio output: May 22 17:36:26 volumio volumio[862]: info: Stoppping Tunnel 1 May 22 17:36:26 volumio sudo[1877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 22 17:36:26 volumio sudo[1877]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:26 volumio sudo[1877]: pam_unix(sudo:session): session closed for user root May 22 17:36:26 volumio volumio[862]: error: Cannot start Volumio Streaming Daemon May 22 17:36:26 volumio volumio[862]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 22 17:36:26 volumio volumio[862]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 22 17:36:26 volumio sudo[1880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 22 17:36:26 volumio sudo[1880]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:26 volumio sudo[1880]: pam_unix(sudo:session): session closed for user root May 22 17:36:26 volumio volumio[862]: info: Remote SSH Stopped May 22 17:36:26 volumio volumio[862]: info: Setting Geolocation for MyVolumio to eu2 May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:26 volumio ntpd[1811]: Soliciting pool server 193.204.114.105 May 22 17:36:26 volumio ntpd[1811]: Soliciting pool server 2a00:6d41:10:1194::2 May 22 17:36:27 volumio volumio[862]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 22 17:36:27 volumio volumio[862]: info: Updating MyVolumio device info May 22 17:36:27 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:27 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:27 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:28 volumio volumio-time-update[638]: volumio-time-update-util: Fetching time from Volumio... May 22 17:36:28 volumio volumio-time-update[638]: volumio-time-update-util: Setting system time to: 2025-05-22 17:36:29 May 22 17:36:28 volumio sudo[1887]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-22 17:36:29 May 22 17:36:28 volumio sudo[1887]: pam_unix(sudo:session): session opened for user root by (uid=0) May 22 17:36:28 volumio dbus-daemon[663]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.15' (uid=0 pid=1888 comm="timedatectl set-time 2025-05-22 17:36:29 ") May 22 17:36:28 volumio systemd[1]: Starting Time & Date Service... May 22 17:36:28 volumio dbus-daemon[663]: [system] Successfully activated service 'org.freedesktop.timedate1' May 22 17:36:28 volumio systemd[1]: Started Time & Date Service. May 22 17:36:29 volumio systemd-timedated[1889]: Changed local time to Thu May 22 17:36:29 2025 May 22 17:36:29 volumio sudo[1887]: pam_unix(sudo:session): session closed for user root May 22 17:36:29 volumio volumio-time-update[638]: volumio-time-update-util: System time updated successfully. May 22 17:36:29 volumio systemd[1]: Started Volumio Time Update Utility. May 22 17:36:29 volumio systemd[1]: Reached target Multi-User System. May 22 17:36:29 volumio systemd[1]: Reached target Graphical Interface. May 22 17:36:29 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 22 17:36:29 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 22 17:36:29 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 22 17:36:29 volumio systemd[1]: Startup finished in 8.612s (firmware) + 2.729s (loader) + 6.833s (kernel) + 40.783s (userspace) = 58.958s. May 22 17:36:29 volumio volumio[862]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 22 17:36:30 volumio ntpd[1811]: receive: Unexpected origin timestamp 0xebd9c2fe.2d709233 does not match aorg 0000000000.00000000 from server@185.157.229.254 xmt 0xebd9c2fe.94c44e82 May 22 17:36:30 volumio ntpd[1811]: receive: Unexpected origin timestamp 0xebd9c2fe.2d7012b0 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xebd9c2fe.96004c48 May 22 17:36:30 volumio ntpd[1811]: receive: Unexpected origin timestamp 0xebd9c2fe.2d6e17c9 does not match aorg 0000000000.00000000 from server@193.204.114.105 xmt 0xebd9c2fe.951e5f1e May 22 17:36:30 volumio ntpd[1811]: receive: Unexpected origin timestamp 0xebd9c2fe.2d713233 does not match aorg 0000000000.00000000 from server@93.94.88.51 xmt 0xebd9c2fe.97c79f89 May 22 17:36:31 volumio volumio[862]: info: MYVOLUMIO: Adding device May 22 17:36:31 volumio volumio[862]: info: MYVOLUMIO: Evaluating Server May 22 17:36:32 volumio volumio[862]: info: Setting Geolocation for MyVolumio to eu7 May 22 17:36:32 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:32 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:32 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:32 volumio volumio[862]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 22 17:36:33 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 22 17:36:33 volumio volumio[862]: info: Updating MyVolumio device info May 22 17:36:33 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:33 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:33 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:34 volumio volumio[862]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 22 17:36:35 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:36:35 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:35 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 22 17:36:35 volumio volumio-remote-updater[644]: No test mode May 22 17:36:35 volumio volumio-remote-updater[644]: No alpha test mode May 22 17:36:35 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 22 17:36:35 volumio volumio[862]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 22 17:36:35 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 22 17:36:39 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 22 17:36:39 volumio volumio[862]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 22 17:36:39 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:39 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:44 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:44 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:44 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 22 17:36:44 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 22 17:36:44 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 22 17:36:44 volumio volumio[862]: info: CoreCommandRouter::volumioGetBrowseSources May 22 17:36:44 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 22 17:36:44 volumio volumio[862]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 22 17:36:44 volumio volumio[862]: info: Completed starting MyVolumio Plugin May 22 17:36:44 volumio volumio[862]: [Metrics] CommandRouter: 45s 821.09ms May 22 17:36:44 volumio volumio[862]: info: CoreCommandRouter::volumiosetStartupVolume May 22 17:36:44 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 22 17:36:44 volumio volumio[862]: info: VolumeController:: Setting startup Volume 40 May 22 17:36:44 volumio volumio[862]: info: VolumeController::SetAlsaVolume40 May 22 17:36:44 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 22 17:36:45 volumio volumio[862]: info: CoreCommandRouter::Close All Modals sent May 22 17:36:45 volumio volumio[862]: info: CoreCommandRouter::Close All Modals sent May 22 17:36:45 volumio volumio[862]: info: CoreStateMachine::pushState May 22 17:36:45 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:45 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 17:36:45 volumio volumio[862]: info: CoreCommandRouter::volumioPushState May 22 17:36:46 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 22 17:36:46 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 22 17:36:46 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 22 17:36:51 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:36:51 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:36:51 volumio volumio[862]: info: Discovery: Getting this device information May 22 17:36:51 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:36:51 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:36:51 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:36:52 volumio volumio[862]: info: BOOT COMPLETED May 22 17:36:59 volumio systemd[1]: systemd-timedated.service: Succeeded. May 22 17:37:21 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 17:37:21 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 17:37:21 volumio volumio[862]: info: Discovery: Getting this device information May 22 17:37:21 volumio volumio[862]: info: CoreCommandRouter::volumioGetState May 22 17:37:21 volumio volumio[862]: info: CorePlayQueue::getTrack 0 May 22 17:37:21 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:37:23 volumio volumio[862]: info: Retrieving Cloud Streaming UI May 22 17:37:23 volumio volumio[862]: info: Getting Tidal Cloud Configuration May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:37:23 volumio volumio[862]: info: Getting Qobuz Cloud Configuration May 22 17:37:23 volumio volumio[862]: info: Asking plugin for UI Config May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:37:23 volumio volumio[862]: info: Getting Spotify Cloud Configuration May 22 17:37:23 volumio volumio[862]: info: Asking plugin for UI Config May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:37:23 volumio volumio[862]: info: Saving Spotify Acccount May 22 17:37:23 volumio volumio[862]: info: Got Tidal Cloud Configuration May 22 17:37:23 volumio volumio[862]: info: Got it May 22 17:37:23 volumio volumio[862]: info: Got it May 22 17:37:23 volumio volumio[862]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin May 22 17:37:23 volumio volumio[862]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetBrowseSources May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetBrowseSources May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::volumioGetBrowseSources May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 22 17:37:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 22 17:37:24 volumio nmbd[784]: [2025/05/22 17:37:24.783024, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 22 17:37:24 volumio nmbd[784]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable May 22 17:37:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 22 17:37:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 22 17:37:26 volumio volumio[862]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:37:26 volumio volumio[862]: Error: getaddrinfo -3007 May 22 17:37:26 volumio volumio[862]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) May 22 17:37:26 volumio volumio[862]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) May 22 17:37:26 volumio volumio[862]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { May 22 17:37:26 volumio volumio[862]: code: -3007, May 22 17:37:26 volumio volumio[862]: errno: -3007, May 22 17:37:26 volumio volumio[862]: syscall: 'getaddrinfo' May 22 17:37:26 volumio volumio[862]: } May 22 17:37:26 volumio volumio[862]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 17:37:26 volumio sudo[2068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 17:36 May 22 17:37:26 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"