-- Logs begin at Wed 2026-01-28 21:54:48 CST, end at Wed 2026-01-28 21:56:32 CST. -- Jan 28 21:55:00 volumio ntpd[710]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 28 21:55:01 volumio dhcpcd[441]: eth0: using static address 192.168.3.21/24 Jan 28 21:55:01 volumio dhcpcd[441]: eth0: adding route to 192.168.3.0/24 Jan 28 21:55:01 volumio dhcpcd[441]: eth0: adding default route via 192.168.3.1 Jan 28 21:55:01 volumio avahi-daemon[396]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.3.21. Jan 28 21:55:01 volumio avahi-daemon[396]: New relevant interface eth0.IPv4 for mDNS. Jan 28 21:55:01 volumio avahi-daemon[396]: Registering new address record for 192.168.3.21 on eth0.IPv4. Jan 28 21:55:01 volumio volumio[606]: info: ------------------------------------------- Jan 28 21:55:01 volumio volumio[606]: info: ----- Volumio3 ---- Jan 28 21:55:01 volumio volumio[606]: info: ------------------------------------------- Jan 28 21:55:01 volumio volumio[606]: info: ----- System startup ---- Jan 28 21:55:01 volumio volumio[606]: info: ------------------------------------------- Jan 28 21:55:01 volumio ntpd[710]: Soliciting pool server 162.159.200.1 Jan 28 21:55:02 volumio ntpd[710]: Listen normally on 3 eth0 192.168.3.21:123 Jan 28 21:55:02 volumio ntpd[710]: new interface(s) found: waking up resolver Jan 28 21:55:03 volumio volumio[606]: info: MYVOLUMIO Environment detected Jan 28 21:55:03 volumio systemd[1]: Started Samba NMB Daemon. Jan 28 21:55:03 volumio nmbd[709]: [2026/01/28 21:55:03.852332, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 28 21:55:03 volumio nmbd[709]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 28 21:55:03 volumio systemd[1]: Starting Samba Winbind Daemon... Jan 28 21:55:03 volumio nmbd[709]: [2026/01/28 21:55:03.884338, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 28 21:55:03 volumio nmbd[709]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.21 for name WORKGROUP<1d>. Jan 28 21:55:03 volumio nmbd[709]: This response was from IP 192.168.3.40, reporting an IP address of 192.168.3.40. Jan 28 21:55:03 volumio volumio[606]: info: Plugin folders cleanup Jan 28 21:55:03 volumio volumio[606]: info: Scanning into folder /volumio/app/plugins/ Jan 28 21:55:03 volumio volumio[606]: info: Scanning category audio_interface Jan 28 21:55:03 volumio volumio[606]: info: Scanning category miscellanea Jan 28 21:55:03 volumio volumio[606]: info: Scanning category music_service Jan 28 21:55:03 volumio volumio[606]: info: Scanning category plugins.json Jan 28 21:55:03 volumio volumio[606]: info: Scanning category system_controller Jan 28 21:55:03 volumio volumio[606]: info: Scanning category user_interface Jan 28 21:55:03 volumio volumio[606]: info: Scanning into folder /data/plugins/ Jan 28 21:55:03 volumio volumio[606]: info: Scanning category music_service Jan 28 21:55:03 volumio volumio[606]: info: Scanning category system_controller Jan 28 21:55:03 volumio volumio[606]: info: Scanning category user_interface Jan 28 21:55:03 volumio volumio[606]: info: Plugin folders cleanup completed Jan 28 21:55:03 volumio volumio[606]: info: ------------------------------------------- Jan 28 21:55:03 volumio volumio[606]: info: ----- Core plugins startup ---- Jan 28 21:55:03 volumio volumio[606]: info: ------------------------------------------- Jan 28 21:55:03 volumio volumio[606]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 21:55:03 volumio volumio[606]: info: Adding plugin upnp to MyMusic Plugins Jan 28 21:55:03 volumio volumio[606]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 21:55:03 volumio volumio[606]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 21:55:03 volumio volumio[606]: info: Loading plugins from folder /data/plugins/ Jan 28 21:55:03 volumio volumio[606]: info: Loading plugin "system"... Jan 28 21:55:04 volumio volumio[606]: info: Loading plugin "appearance"... Jan 28 21:55:04 volumio winbindd[917]: [2026/01/28 21:55:04.536852, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 28 21:55:04 volumio winbindd[917]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 28 21:55:04 volumio winbindd[917]: [2026/01/28 21:55:04.557231, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 28 21:55:04 volumio winbindd[917]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 28 21:55:04 volumio systemd[1]: Started Samba Winbind Daemon. Jan 28 21:55:04 volumio systemd[1]: Starting Samba SMB Daemon... Jan 28 21:55:05 volumio smbd[925]: [2026/01/28 21:55:05.399254, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 28 21:55:05 volumio smbd[925]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 28 21:55:05 volumio systemd[1]: Started Samba SMB Daemon. Jan 28 21:55:05 volumio systemd[1]: Reached target Multi-User System. Jan 28 21:55:05 volumio systemd[1]: Reached target Graphical Interface. Jan 28 21:55:05 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 28 21:55:05 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 28 21:55:05 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 28 21:55:05 volumio systemd[1]: Startup finished in 22.614s (kernel) + 18.509s (userspace) = 41.124s. Jan 28 21:55:06 volumio volumio-remote-updater[391]: [2026-01-28 21:55:06] [connect] Successful connection Jan 28 21:55:06 volumio volumio[606]: info: Loading plugin "network"... Jan 28 21:55:06 volumio volumio[606]: info: Refreshing Cached IP Addresses Jan 28 21:55:06 volumio volumio[606]: info: Loading plugin "services"... Jan 28 21:55:06 volumio sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 21:55:06 volumio sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:06 volumio volumio[606]: info: Loading plugin "alsa_controller"... Jan 28 21:55:06 volumio sudo[939]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:06 volumio sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 21:55:06 volumio sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:06 volumio sudo[941]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:07 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 21:55:07 volumio volumio[606]: info: Loading plugin "wizard"... Jan 28 21:55:07 volumio volumio[606]: info: Loading plugin "networkfs"... Jan 28 21:55:07 volumio sudo[949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=fido1312,password=Fido1312,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.40/2T /mnt/NAS/2T Jan 28 21:55:07 volumio sudo[949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:07 volumio volumio[606]: info: Starting Udev Watcher for removable devices Jan 28 21:55:07 volumio sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=fido1312,password=Fido1312,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.21/usb /mnt/NAS/usb Jan 28 21:55:07 volumio sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:07 volumio kernel: FS-Cache: Loaded Jan 28 21:55:07 volumio kernel: Key type dns_resolver registered Jan 28 21:55:07 volumio volumio[606]: info: Ignoring mount for partition: boot Jan 28 21:55:07 volumio volumio[606]: info: Ignoring mount for partition: volumio Jan 28 21:55:07 volumio volumio[606]: info: Ignoring mount for partition: volumio_data Jan 28 21:55:07 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 21:55:07 volumio volumio[606]: info: Loading plugin "volumio_command_line_client"... Jan 28 21:55:07 volumio volumio[606]: info: Loading plugin "upnp"... Jan 28 21:55:07 volumio volumio[606]: info: [1769608507438] Starting Upmpd Daemon Jan 28 21:55:07 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 21:55:07 volumio volumio[606]: info: Loading plugin "my_music"... Jan 28 21:55:07 volumio volumio[606]: info: Loading plugin "mpd"... Jan 28 21:55:07 volumio kernel: Key type cifs.spnego registered Jan 28 21:55:07 volumio kernel: Key type cifs.idmap registered Jan 28 21:55:07 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jan 28 21:55:07 volumio kernel: CIFS: Attempting to mount //192.168.3.40/2T Jan 28 21:55:07 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jan 28 21:55:07 volumio kernel: CIFS: Attempting to mount //192.168.3.21/usb Jan 28 21:55:07 volumio sudo[949]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:07 volumio sudo[951]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:08 volumio volumio[606]: info: Loading plugin "upnp_browser"... Jan 28 21:55:08 volumio volumio[606]: info: Loading plugin "alarm-clock"... Jan 28 21:55:08 volumio volumio[606]: info: Loading plugin "airplay_emulation"... Jan 28 21:55:08 volumio volumio[606]: info: Starting Shairport Sync Jan 28 21:55:08 volumio volumio[606]: info: Loading plugin "last_100"... Jan 28 21:55:08 volumio volumio[606]: info: Loading plugin "webradio"... Jan 28 21:55:09 volumio volumio[606]: info: Loading plugin "i2s_dacs"... Jan 28 21:55:09 volumio volumio[606]: info: I2S DAC not set, start Auto-detection Jan 28 21:55:09 volumio volumio[606]: info: Loading plugin "volumiodiscovery"... Jan 28 21:55:09 volumio volumio[606]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 21:55:09 volumio volumio[606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 21:55:09 volumio volumio[606]: *** WARNING *** For more information see Jan 28 21:55:09 volumio volumio[606]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 21:55:09 volumio volumio[606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 21:55:09 volumio volumio[606]: *** WARNING *** For more information see Jan 28 21:55:09 volumio node[606]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 21:55:09 volumio node[606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 21:55:09 volumio node[606]: *** WARNING *** For more information see Jan 28 21:55:09 volumio node[606]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 21:55:09 volumio node[606]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 21:55:09 volumio node[606]: *** WARNING *** For more information see Jan 28 21:55:09 volumio volumio[606]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 21:55:09 volumio volumio[606]: info: Discovery: Started advertising with name: Volumio Jan 28 21:55:09 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 21:55:09 volumio volumio[606]: info: Loading plugin "autostart"... Jan 28 21:55:09 volumio dbus-daemon[399]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.9' (uid=0 pid=890 comm="/usr/lib/chromium/chromium --show-component-extens") Jan 28 21:55:09 volumio systemd[1]: Starting Bluetooth service... Jan 28 21:55:09 volumio volumio[606]: info: Applying required configuration parameters for plugin autostart Jan 28 21:55:09 volumio volumio[606]: info: AutoStart - onVolumioStart - read config.json Jan 28 21:55:09 volumio volumio[606]: info: Loading plugin "peppy_screensaver"... Jan 28 21:55:10 volumio kernel: Bluetooth: Core ver 2.22 Jan 28 21:55:10 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Jan 28 21:55:10 volumio kernel: Bluetooth: HCI device and connection manager initialized Jan 28 21:55:10 volumio kernel: Bluetooth: HCI socket layer initialized Jan 28 21:55:10 volumio kernel: Bluetooth: L2CAP socket layer initialized Jan 28 21:55:10 volumio kernel: Bluetooth: SCO socket layer initialized Jan 28 21:55:10 volumio bluetoothd[1006]: Bluetooth daemon 5.50 Jan 28 21:55:10 volumio bluetoothd[1006]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 28 21:55:10 volumio dbus-daemon[399]: [system] Successfully activated service 'org.bluez' Jan 28 21:55:10 volumio systemd[1]: Started Bluetooth service. Jan 28 21:55:10 volumio bluetoothd[1006]: Starting SDP server Jan 28 21:55:10 volumio bluetoothd[1006]: Excluding (cli) sap Jan 28 21:55:10 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 28 21:55:10 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jan 28 21:55:10 volumio kernel: Bluetooth: BNEP socket layer initialized Jan 28 21:55:10 volumio dbus-daemon[399]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=1006 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 28 21:55:10 volumio bluetoothd[1006]: Bluetooth management interface 1.22 initialized Jan 28 21:55:10 volumio systemd[1]: Starting Hostname Service... Jan 28 21:55:10 volumio dbus-daemon[399]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 28 21:55:10 volumio systemd[1]: Started Hostname Service. Jan 28 21:55:11 volumio volumio[606]: info: Applying required configuration parameters for plugin peppy_screensaver Jan 28 21:55:11 volumio volumio[606]: info: Loading plugin "outputs"... Jan 28 21:55:11 volumio volumio[606]: info: Loading plugin "albumart"... Jan 28 21:55:11 volumio volumio[606]: info: Plugin example_plugin is not enabled Jan 28 21:55:11 volumio volumio[606]: info: Loading plugin "inputs"... Jan 28 21:55:11 volumio volumio[606]: info: Loading plugin "updater_comm"... Jan 28 21:55:11 volumio volumio[606]: Forking 1 albumart workers Jan 28 21:55:12 volumio volumio[606]: info: Plugin mpdemulation is not enabled Jan 28 21:55:12 volumio volumio[606]: info: Loading plugin "rest_api"... Jan 28 21:55:12 volumio volumio[606]: info: Loading plugin "websocket"... Jan 28 21:55:12 volumio volumio[606]: info: Loading plugin "RoonBridge"... Jan 28 21:55:13 volumio volumio[606]: info: Applying required configuration parameters for plugin RoonBridge Jan 28 21:55:13 volumio volumio[606]: info: Loading i18n strings for locale zh Jan 28 21:55:13 volumio volumio[606]: Updating browse sources language Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 21:55:13 volumio volumio[606]: Starting albumart workers Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::initPlayerControls Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 21:55:13 volumio volumio[606]: Express server listening on port 3000 Jan 28 21:55:13 volumio volumio[606]: [Metrics] WebUI: 13s 453.18ms Jan 28 21:55:13 volumio volumio[606]: info: Setting Device type: x86 Jan 28 21:55:13 volumio volumio[606]: info: CoreStateMachine::resetVolumioState Jan 28 21:55:13 volumio volumio[606]: info: CoreStateMachine::getcurrentVolume Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:13 volumio volumio[606]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 28 21:55:13 volumio volumio[606]: info: Completed loading Core Plugins Jan 28 21:55:13 volumio volumio[606]: info: Preparing to generate the ALSA configuration file Jan 28 21:55:13 volumio volumio[606]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 28 21:55:13 volumio volumio[606]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jan 28 21:55:13 volumio volumio[606]: info: Reading ALSA contributions from plugins. Jan 28 21:55:13 volumio volumio[606]: verbose: New Socket.io Connection to 192.168.3.21:3000 from 192.168.3.13 UA: Dart/3.10 (dart:io) Total Clients: 1 Jan 28 21:55:13 volumio volumio[606]: verbose: New Socket.io Connection to 192.168.3.21:3000 from 192.168.3.13 UA: Dart/3.10 (dart:io) Total Clients: 2 Jan 28 21:55:13 volumio volumio[606]: verbose: New Socket.io Connection to 192.168.3.21:3000 from 192.168.3.13 UA: Dart/3.10 (dart:io) Total Clients: 3 Jan 28 21:55:13 volumio volumio[606]: verbose: New Socket.io Connection to 192.168.3.21:3000 from 192.168.3.13 UA: Dart/3.10 (dart:io) Total Clients: 4 Jan 28 21:55:13 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:13 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:13 volumio volumio[606]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Jan 28 21:55:14 volumio volumio[606]: info: Reloading queue from file Jan 28 21:55:14 volumio volumio[606]: info: VolumeController:: Volume=50 Mute =false Jan 28 21:55:14 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:14 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:14 volumio volumio[606]: info: CoreStateMachine::updateTrackBlock Jan 28 21:55:14 volumio volumio[606]: info: CorePlayQueue::getTrackBlock Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 21:55:14 volumio volumio[606]: info: CoreStateMachine::setRepeat true single undefined Jan 28 21:55:14 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:14 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:14 volumio volumio[606]: info: CoreStateMachine::setRandom null Jan 28 21:55:14 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:14 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 21:55:14 volumio volumio[606]: info: Discovery: Getting this device information Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:14 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 21:55:14 volumio volumio[606]: info: VolumeController:: Volume=50 Mute =false Jan 28 21:55:14 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:14 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:14 volumio volumio[606]: info: Asound.conf file unchanged, so no further update is needed Jan 28 21:55:14 volumio volumio[606]: info: Output device has changed, restarting MPD Jan 28 21:55:14 volumio sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 21:55:14 volumio volumio[606]: info: Output device has changed, restarting Shairport Sync Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:14 volumio sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:14 volumio sudo[1148]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:14 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 21:55:14 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:14 volumio volumio[606]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 21:55:14 volumio volumio[606]: info: ___________ START PLUGINS ___________ Jan 28 21:55:14 volumio volumio[606]: info: ControllerMpd::onStart: Initializing MPD Jan 28 21:55:14 volumio volumio[606]: info: Creating MPD Configuration file Jan 28 21:55:14 volumio systemd[1]: Listening on mpd.socket. Jan 28 21:55:14 volumio systemd[1]: Starting Music Player Daemon... Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 21:55:14 volumio volumio[606]: info: [1769608514661] CoreMusicLibrary::Adding element 媒体服务器 Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 21:55:14 volumio sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 21:55:14 volumio sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:14 volumio sudo[1158]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:14 volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 21:55:14 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:14 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 28 21:55:14 volumio systemd[1]: mpd.service: Succeeded. Jan 28 21:55:14 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:14 volumio systemd[1]: Starting Music Player Daemon... Jan 28 21:55:14 volumio volumio[606]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:14 volumio volumio[606]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 21:55:14 volumio volumio[606]: info: [1769608514926] CoreMusicLibrary::Adding element Last_100 Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 21:55:14 volumio volumio[606]: info: [1769608514930] CoreMusicLibrary::Adding element Webradio Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 21:55:14 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 21:55:14 volumio volumio[606]: info: AutoStart - onStart Jan 28 21:55:14 volumio volumio[606]: info: Loading i18n strings for locale zh Jan 28 21:55:14 volumio volumio[606]: info: /tmp/myfifo created Jan 28 21:55:15 volumio volumio[606]: info: /tmp/myfifosa created Jan 28 21:55:15 volumio sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jan 28 21:55:15 volumio sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:15 volumio sudo[1175]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:15 volumio volumio[606]: info: snd-dummy loaded Jan 28 21:55:15 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:15 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:15 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:15 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jan 28 21:55:15 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:15 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:15 volumio sudo[1190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl Jan 28 21:55:15 volumio sudo[1190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:15 volumio sudo[1190]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:15 volumio volumio[606]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 21:55:15 volumio volumio[606]: info: Volumio Calling Home Jan 28 21:55:15 volumio sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jan 28 21:55:15 volumio sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:15 volumio systemd[1]: Started RoonBridge. Jan 28 21:55:15 volumio sudo[1196]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:15 volumio volumio[606]: info: Discovery: adding a99547a7-f380-4a98-b83c-927c021eec55 Jan 28 21:55:15 volumio volumio[606]: info: Discovery: Found device Volumio Jan 28 21:55:15 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:15 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:16 volumio volumio[606]: info: MPD Permissions set Jan 28 21:55:16 volumio volumio[606]: info: MPD Permissions set Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:16 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:16 volumio volumio[606]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Jan 28 21:55:16 volumio volumio[606]: info: Volumio called home Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jan 28 21:55:16 volumio sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 21:55:16 volumio sudo[1215]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:16 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Jan 28 21:55:16 volumio sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 21:55:16 volumio sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:16 volumio volumio[606]: info: Starting Shairport Sync Jan 28 21:55:17 volumio sudo[1230]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:17 volumio volumio[606]: info: Starting Shairport Sync Jan 28 21:55:17 volumio volumio[606]: info: Starting Shairport Sync Jan 28 21:55:17 volumio sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 21:55:17 volumio sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:17 volumio volumio[606]: info: Starting Shairport Sync Jan 28 21:55:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 21:55:17 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 28 21:55:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 21:55:17 volumio sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 21:55:17 volumio sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 21:55:17 volumio sudo[1233]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 21:55:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 28 21:55:17 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 28 21:55:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 21:55:17 volumio sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 21:55:17 volumio sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 21:55:17 volumio sudo[1235]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:17 volumio sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 21:55:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 21:55:17 volumio sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:17 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 28 21:55:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 21:55:17 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jan 28 21:55:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 21:55:17 volumio sudo[1238]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 21:55:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 28 21:55:17 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 28 21:55:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 21:55:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 21:55:17 volumio sudo[1242]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:17 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 21:55:17 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:17 volumio sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 21:55:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 28 21:55:17 volumio systemd[1]: mpd.service: Succeeded. Jan 28 21:55:17 volumio RoonBridge[1201]: 00:00:00.046 Warn: get lock file path: /tmp/.rnbgem0- Jan 28 21:55:17 volumio systemd[1]: Stopped Music Player Daemon. Jan 28 21:55:17 volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:17 volumio systemd[1]: Starting Music Player Daemon... Jan 28 21:55:17 volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:18 volumio sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 21:55:18 volumio sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:18 volumio sudo[1257]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:18 volumio volumio[606]: info: Shairport-Sync Started Jan 28 21:55:18 volumio volumio[606]: Error adding Membership: Error: addMembership EINVAL Jan 28 21:55:18 volumio volumio[606]: info: Shairport-Sync Started Jan 28 21:55:18 volumio volumio[606]: info: MPD Permissions set Jan 28 21:55:18 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:18 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:18 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:18 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:18 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:18 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 28 21:55:18 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:55:18 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:18 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:18 volumio RoonBridge[1201]: 00:00:01.055 Trace: [childprocess] using unix child process Jan 28 21:55:18 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 28 21:55:18 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:18 volumio RoonBridge[1201]: 00:00:01.213 Debug: PathForResource, filename: ../.update Jan 28 21:55:18 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 28 21:55:18 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:18 volumio volumio[606]: info: Upmpdcli Daemon Started Jan 28 21:55:18 volumio RoonBridge[1201]: 00:00:01.295 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jan 28 21:55:18 volumio RoonBridge[1201]: 00:00:01.315 Debug: PathForResource, filename: RAATServer Jan 28 21:55:18 volumio RoonBridge[1201]: 00:00:01.315 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jan 28 21:55:18 volumio RoonBridge[1201]: 00:00:01.316 Debug: PathForResource, filename: RoonBridgeHelper Jan 28 21:55:18 volumio RoonBridge[1201]: 00:00:01.316 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jan 28 21:55:18 volumio RoonBridge[1201]: Initializing Jan 28 21:55:19 volumio RoonBridge[1201]: 00:00:01.480 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Jan 28 21:55:19 volumio RoonBridge[1201]: 00:00:01.607 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jan 28 21:55:19 volumio RoonBridge[1201]: Not Running (.o) Jan 28 21:55:20 volumio RoonBridge[1201]: Running Jan 28 21:55:21 volumio mpd[1258]: Jan 28 21:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 21:55:21 volumio volumio-remote-updater[391]: [2026-01-28 21:55:21] [connect] Successful connection Jan 28 21:55:21 volumio volumio-remote-updater[391]: [2026-01-28 21:55:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1769608521 101 Jan 28 21:55:21 volumio systemd[1]: Started Music Player Daemon. Jan 28 21:55:21 volumio volumio[606]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 7 Jan 28 21:55:21 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:21 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:21 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:21 volumio volumio[606]: Upnp client error: Error: This socket has been ended by the other party Jan 28 21:55:21 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 28 21:55:21 volumio volumio[606]: info: Completed starting Core Plugins Jan 28 21:55:21 volumio volumio[606]: info: ------------------------------------------- Jan 28 21:55:21 volumio volumio[606]: info: ----- MyVolumio plugins startup ---- Jan 28 21:55:21 volumio volumio[606]: info: ------------------------------------------- Jan 28 21:55:21 volumio volumio[606]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 28 21:55:21 volumio volumio[606]: info: MPD running with PID1258 Jan 28 21:55:21 volumio volumio[606]: ,establishing connection Jan 28 21:55:21 volumio volumio[606]: error: MPD error: The expression evaluated to a falsy value: Jan 28 21:55:21 volumio volumio[606]: assert.ok(self.idling) Jan 28 21:55:21 volumio volumio[606]: error: The expression evaluated to a falsy value: Jan 28 21:55:21 volumio volumio[606]: assert.ok(self.idling) Jan 28 21:55:21 volumio volumio[606]: error: MPD error: The expression evaluated to a falsy value: Jan 28 21:55:21 volumio volumio[606]: assert.ok(self.idling) Jan 28 21:55:21 volumio volumio[606]: error: The expression evaluated to a falsy value: Jan 28 21:55:21 volumio volumio[606]: assert.ok(self.idling) Jan 28 21:55:21 volumio volumio[606]: error: MPD error: The expression evaluated to a falsy value: Jan 28 21:55:21 volumio volumio[606]: assert.ok(self.idling) Jan 28 21:55:21 volumio volumio[606]: error: The expression evaluated to a falsy value: Jan 28 21:55:21 volumio volumio[606]: assert.ok(self.idling) Jan 28 21:55:21 volumio volumio[606]: error: updateQueue error: null Jan 28 21:55:22 volumio RoonBridge[1201]: 00:00:00.176 Warn: get lock file path: /tmp/.rnbhgem0- Jan 28 21:55:22 volumio volumio[606]: 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 Total Clients: 8 Jan 28 21:55:23 volumio volumio[1271]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 28 21:55:24 volumio volumio[1271]: ..................................++++ Jan 28 21:55:24 volumio volumio[606]: info: AutoStart - Plugin is starting Jan 28 21:55:24 volumio volumio[606]: info: CoreCommandRouter::volumioGetQueue Jan 28 21:55:24 volumio volumio[606]: info: CoreStateMachine::getQueue Jan 28 21:55:24 volumio volumio[606]: info: CorePlayQueue::getQueue Jan 28 21:55:24 volumio volumio[606]: info: AutoStart - start playing Jan 28 21:55:24 volumio volumio[606]: info: AutoStart - start playing with no specific position Jan 28 21:55:24 volumio volumio[606]: info: CoreCommandRouter::volumioPlay Jan 28 21:55:24 volumio volumio[606]: info: CoreStateMachine::play index 0 Jan 28 21:55:24 volumio volumio[606]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 21:55:24 volumio volumio[606]: info: CoreStateMachine::stop Jan 28 21:55:24 volumio volumio[606]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 21:55:24 volumio volumio[606]: info: CoreStateMachine::play index undefined Jan 28 21:55:24 volumio volumio[606]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 21:55:24 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:24 volumio volumio[606]: info: CoreStateMachine::startPlaybackTimer Jan 28 21:55:24 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:24 volumio volumio[606]: verbose: ControllerMpd::clearAddPlayTracks NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav Jan 28 21:55:24 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand stop took 19 milliseconds Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces system playlist update Jan 28 21:55:25 volumio volumio[606]: info: Ignoring MPD Status Update Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand clear took 9 milliseconds Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand add "NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav" Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces system playlist update Jan 28 21:55:25 volumio volumio[606]: info: Ignoring MPD Status Update Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces system playlist update Jan 28 21:55:25 volumio volumio[606]: info: Ignoring MPD Status Update Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces system playlist update Jan 28 21:55:25 volumio volumio[606]: info: Ignoring MPD Status Update Jan 28 21:55:25 volumio volumio[606]: error: updateQueue error: null Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces system playlist update Jan 28 21:55:25 volumio volumio[606]: info: Ignoring MPD Status Update Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 40ms Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand add "NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav" took 32 milliseconds Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 32ms Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 25ms Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand play Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces system playlist update Jan 28 21:55:25 volumio volumio[606]: info: Ignoring MPD Status Update Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces system playlist update Jan 28 21:55:25 volumio volumio[606]: info: Ignoring MPD Status Update Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 90ms Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 79ms Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand play took 69 milliseconds Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 63ms Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 81ms Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces system playlist update Jan 28 21:55:25 volumio volumio[606]: info: Ignoring MPD Status Update Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand status took 96 milliseconds Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand status took 101 milliseconds Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand status took 36 milliseconds Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:25 volumio volumio[606]: info: Jan 28 21:55:25 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 68ms Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand status took 56 milliseconds Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand playlistinfo took 36 milliseconds Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand playlistinfo took 35 milliseconds Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand status took 25 milliseconds Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand status took 23 milliseconds Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand status took 14 milliseconds Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:25 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState stateService play Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus stop Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:25 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState stateService play Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus play Jan 28 21:55:25 volumio volumio[606]: info: Received an update from plugin. extracting info from payload Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:25 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState stateService play Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus play Jan 28 21:55:25 volumio volumio[606]: info: Received an update from plugin. extracting info from payload Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 258ms Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 294ms Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 249ms Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand playlistinfo took 212 milliseconds Jan 28 21:55:25 volumio volumio[606]: info: sendMpdCommand playlistinfo took 203 milliseconds Jan 28 21:55:25 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: verbose: STATE SERVICE {"status":"play","position":0,"seek":660,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:25 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState stateService play Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus play Jan 28 21:55:25 volumio volumio[606]: info: Received an update from plugin. extracting info from payload Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: verbose: STATE SERVICE {"status":"play","position":0,"seek":660,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:25 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState stateService play Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus play Jan 28 21:55:25 volumio volumio[606]: info: Received an update from plugin. extracting info from payload Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:25 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState stateService play Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus play Jan 28 21:55:25 volumio volumio[606]: info: Received an update from plugin. extracting info from payload Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:25 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState stateService play Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus play Jan 28 21:55:25 volumio volumio[606]: info: Received an update from plugin. extracting info from payload Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 498ms Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 461ms Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 457ms Jan 28 21:55:25 volumio volumio[606]: info: ------------------------------ 457ms Jan 28 21:55:25 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:25 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:25 volumio sudo[1504]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 28 21:55:25 volumio sudo[1504]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:26 volumio volumio[1271]: .......................++++ Jan 28 21:55:26 volumio volumio[1271]: e is 65537 (0x010001) Jan 28 21:55:26 volumio volumio[1271]: writing RSA key Jan 28 21:55:27 volumio RoonBridge[1201]: 00:00:09.790 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Jan 28 21:55:27 volumio RoonBridge[1201]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Jan 28 21:55:27 volumio RoonBridge[1201]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Jan 28 21:55:27 volumio RoonBridge[1201]: Not Running (.o) Jan 28 21:55:27 volumio RoonBridge[1201]: 00:00:09.916 Debug: PathForResource, filename: ../.update Jan 28 21:55:27 volumio RoonBridge[1201]: 00:00:09.916 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jan 28 21:55:27 volumio volumio[606]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 28 21:55:29 volumio RoonBridge[1201]: 00:00:11.934 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jan 28 21:55:29 volumio RoonBridge[1201]: Running Jan 28 21:55:31 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 21:55:31 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 21:55:31 volumio volumio[606]: info: Discovery: Getting this device information Jan 28 21:55:31 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:31 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:31 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 21:55:34 volumio volumio[606]: 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 Total Clients: 9 Jan 28 21:55:35 volumio volumio[606]: Cannot compose Albumart path Jan 28 21:55:35 volumio RoonBridge[1201]: 00:00:17.961 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Jan 28 21:55:35 volumio RoonBridge[1201]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Jan 28 21:55:35 volumio RoonBridge[1201]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Jan 28 21:55:35 volumio RoonBridge[1201]: 00:00:17.962 Debug: PathForResource, filename: ../.update Jan 28 21:55:35 volumio RoonBridge[1201]: 00:00:17.962 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jan 28 21:55:35 volumio RoonBridge[1201]: Not Running (.o) Jan 28 21:55:36 volumio volumio[606]: Cannot compose Albumart path Jan 28 21:55:36 volumio volumio[606]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 10 Jan 28 21:55:36 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 28 21:55:36 volumio volumio[606]: Upnp client error: Error: This socket has been ended by the other party Jan 28 21:55:36 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 28 21:55:36 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 28 21:55:36 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:36 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:37 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 28 21:55:37 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 28 21:55:37 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 28 21:55:37 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:37 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:37 volumio sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 28 21:55:37 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:37 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:37 volumio sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 28 21:55:37 volumio RoonBridge[1201]: 00:00:19.973 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jan 28 21:55:37 volumio sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:37 volumio RoonBridge[1201]: Running Jan 28 21:55:37 volumio sudo[1577]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:37 volumio sudo[1582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 28 21:55:37 volumio sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:37 volumio sudo[1582]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:37 volumio sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 21:55:37 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:37 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:37 volumio sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 21:55:37 volumio sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:37 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 28 21:55:37 volumio volumio[606]: info: Adding plugin bluetooth to MyMusic Plugins Jan 28 21:55:37 volumio volumio[606]: info: Adding plugin multiroom to MyMusic Plugins Jan 28 21:55:37 volumio volumio[606]: info: Adding plugin metavolumio to MyMusic Plugins Jan 28 21:55:37 volumio volumio[606]: info: Adding plugin cd_controller to MyMusic Plugins Jan 28 21:55:37 volumio volumio[606]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 28 21:55:37 volumio volumio[606]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 28 21:55:37 volumio volumio[606]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 28 21:55:40 volumio volumio[606]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 28 21:55:40 volumio volumio[606]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 28 21:55:40 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:40 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:40 volumio volumio[606]: info: Starting MyVolumio Remote Streaming Endpoints Jan 28 21:55:40 volumio volumio[606]: info: MyVolumio not started Jan 28 21:55:40 volumio volumio[606]: info: Initializing device activation check Jan 28 21:55:40 volumio volumio[606]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 28 21:55:40 volumio volumio[606]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 28 21:55:40 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jan 28 21:55:42 volumio volumio[606]: info: Starting Streaming Service Transparent Proxy Jan 28 21:55:42 volumio volumio[606]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 28 21:55:42 volumio volumio[606]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 28 21:55:42 volumio volumio[606]: info: Streaming services startup Jan 28 21:55:42 volumio volumio[606]: info: Starting Streaming Daemon Jan 28 21:55:42 volumio sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 28 21:55:42 volumio sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:42 volumio sudo[1614]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:42 volumio volumio[606]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 28 21:55:43 volumio volumio[606]: error: Cannot start Volumio Streaming Daemon Jan 28 21:55:43 volumio volumio[606]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 28 21:55:43 volumio volumio[606]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 28 21:55:43 volumio volumio[606]: Upnp client error: Error: This socket has been ended by the other party Jan 28 21:55:43 volumio volumio[606]: Upnp client error: Error: This socket has been ended by the other party Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::volumioGetVisibleSources Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:43 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:43 volumio RoonBridge[1201]: 00:00:25.993 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Jan 28 21:55:43 volumio RoonBridge[1201]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Jan 28 21:55:43 volumio RoonBridge[1201]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Jan 28 21:55:43 volumio RoonBridge[1201]: 00:00:26.001 Debug: PathForResource, filename: ../.update Jan 28 21:55:43 volumio RoonBridge[1201]: 00:00:26.001 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 21:55:43 volumio volumio[606]: info: Discovery: Getting this device information Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:43 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 21:55:43 volumio RoonBridge[1201]: Not Running (.o) Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 21:55:43 volumio volumio[606]: info: Discovery: Getting this device information Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:43 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 28 21:55:43 volumio volumio[606]: verbose: New Socket.io Connection to 192.168.3.21:3000 from 192.168.3.13 UA: Dart/3.10 (dart:io) Total Clients: 11 Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:43 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 28 21:55:43 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 28 21:55:44 volumio volumio[606]: info: Received Get System Info Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 21:55:44 volumio volumio[606]: info: Discovery: Getting this device information Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:44 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:44 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:44 volumio volumio[606]: info: Listing playlists Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 21:55:44 volumio volumio[606]: info: Received Get System Info Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 21:55:44 volumio volumio[606]: info: Discovery: Getting this device information Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:44 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 28 21:55:44 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 21:55:45 volumio sudo[1625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 21:55:45 volumio sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 21:55:45 volumio sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:45 volumio sudo[1625]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:45 volumio sudo[1627]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:45 volumio sudo[1625]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:45 volumio volumio[606]: verbose: New Socket.io Connection to 192.168.3.21 from 192.168.3.13 UA: Mozilla/5.0 (Linux; Android 16; PKG110 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.59 Mobile Safari/537.36 Total Clients: 11 Jan 28 21:55:45 volumio volumio[606]: STREAMING PROXY: Starting server on port 3245 Jan 28 21:55:45 volumio volumio[606]: Node JS runtime: 14 Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 21:55:45 volumio RoonBridge[1201]: 00:00:28.002 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Jan 28 21:55:45 volumio RoonBridge[1201]: Running Jan 28 21:55:45 volumio sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 21:55:45 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:45 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:45 volumio sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 21:55:45 volumio sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:55:45 volumio sudo[1643]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:45 volumio volumio[606]: verbose: New Socket.io Connection to 192.168.3.21 from 192.168.3.13 UA: Mozilla/5.0 (Linux; Android 16; PKG110 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.59 Mobile Safari/537.36 Total Clients: 12 Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::volumioGetVisibleSources Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:45 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 28 21:55:45 volumio volumio[606]: info: Received Get System Info Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 21:55:45 volumio volumio[606]: info: Discovery: Getting this device information Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:45 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:45 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:45 volumio volumio[606]: info: Listing playlists Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 28 21:55:45 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 21:55:47 volumio volumio[606]: info: Received Get System Info Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 21:55:47 volumio volumio[606]: info: Discovery: Getting this device information Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:47 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::volumioPause Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::pause Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::stPlaybackTimer Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::servicePause Jan 28 21:55:47 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::servicePause Jan 28 21:55:47 volumio volumio[606]: info: ControllerMpd::pause Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand pause Jan 28 21:55:47 volumio volumio[606]: info: sendMpdCommand pause took 6 milliseconds Jan 28 21:55:47 volumio volumio[606]: info: Jan 28 21:55:47 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:47 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:47 volumio volumio[606]: info: Jan 28 21:55:47 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:47 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:47 volumio volumio[606]: info: Jan 28 21:55:47 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:47 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:47 volumio volumio[606]: info: Jan 28 21:55:47 volumio volumio[606]: ---------------------------- MPD announces state update: player Jan 28 21:55:47 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:55:47 volumio volumio[606]: info: sendMpdCommand status took 16 milliseconds Jan 28 21:55:47 volumio volumio[606]: info: sendMpdCommand status took 11 milliseconds Jan 28 21:55:47 volumio volumio[606]: info: sendMpdCommand status took 9 milliseconds Jan 28 21:55:47 volumio volumio[606]: info: sendMpdCommand status took 8 milliseconds Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:55:47 volumio volumio[606]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 28 21:55:47 volumio volumio[606]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 28 21:55:47 volumio volumio[606]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 28 21:55:47 volumio volumio[606]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:55:47 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:55:47 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:47 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:47 volumio volumio[606]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22971,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:47 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::syncState stateService pause Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus pause Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:47 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::stPlaybackTimer Jan 28 21:55:47 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:47 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:47 volumio volumio[606]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22971,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:47 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::syncState stateService pause Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus pause Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:47 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::stPlaybackTimer Jan 28 21:55:47 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:47 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:47 volumio volumio[606]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22971,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:47 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::syncState stateService pause Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus pause Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:47 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::stPlaybackTimer Jan 28 21:55:47 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:55:47 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:47 volumio volumio[606]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22971,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:55:47 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::syncState stateService pause Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus pause Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:55:47 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:47 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:55:47 volumio volumio[606]: info: CoreStateMachine::stPlaybackTimer Jan 28 21:55:47 volumio volumio[606]: info: ------------------------------ 197ms Jan 28 21:55:47 volumio volumio[606]: info: ------------------------------ 192ms Jan 28 21:55:47 volumio volumio[606]: info: ------------------------------ 188ms Jan 28 21:55:47 volumio volumio[606]: info: ------------------------------ 190ms Jan 28 21:55:47 volumio sudo[1504]: pam_unix(sudo:session): session closed for user root Jan 28 21:55:48 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 28 21:55:48 volumio volumio-remote-updater[391]: No test mode Jan 28 21:55:48 volumio volumio-remote-updater[391]: No alpha test mode Jan 28 21:55:48 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 28 21:55:48 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 28 21:55:48 volumio volumio[606]: info: CoreCommandRouter::volumioGetQueue Jan 28 21:55:48 volumio volumio[606]: info: CoreStateMachine::getQueue Jan 28 21:55:48 volumio volumio[606]: info: CorePlayQueue::getQueue Jan 28 21:55:48 volumio volumio[606]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES



    • Fix for lost MPD database after reboot

 

    • Fix for TIDAL authentication refresh

 

    • Fix TIDAL low quality (AAC) playback

 

    • Fix for DLNA Server discovery
    • Fix for Plugins installation



NEW ADDITIONS



    • Update Bluez to v5.83

 

    • Update TIDAL SDK

 

    • Add Bluetooth A2DP playback

 

","title":"Update v3.886","updateavailable":true} Jan 28 21:55:48 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 28 21:55:49 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 21:55:49 volumio volumio[606]: info: CURURI: music-library/NAS Jan 28 21:55:49 volumio volumio[606]: info: Preload queue cleared Jan 28 21:55:52 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 21:55:52 volumio volumio[606]: info: CURURI: music-library Jan 28 21:55:52 volumio volumio[606]: info: Preload queue cleared Jan 28 21:55:53 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 21:55:53 volumio volumio[606]: info: CURURI: music-library/NAS Jan 28 21:55:53 volumio volumio[606]: info: Preload queue cleared Jan 28 21:55:55 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 21:55:55 volumio volumio[606]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Jan 28 21:55:55 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:55 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:55:58 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:55:58 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:00 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:56:00 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 28 21:56:00 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 28 21:56:00 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 28 21:56:00 volumio volumio[606]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 21:56:00 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 21:56:02 volumio ntpd[710]: Soliciting pool server 139.199.214.202 Jan 28 21:56:02 volumio volumio[606]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 28 21:56:02 volumio volumio[606]: info: Completed starting MyVolumio Plugin Jan 28 21:56:02 volumio volumio[606]: info: BOOT COMPLETED Jan 28 21:56:02 volumio volumio[606]: [Metrics] CommandRouter: 61s 473.52ms Jan 28 21:56:02 volumio volumio[606]: info: CoreCommandRouter::volumiosetStartupVolume Jan 28 21:56:02 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 21:56:02 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 21:56:02 volumio volumio[606]: info: CoreCommandRouter::Close All Modals sent Jan 28 21:56:02 volumio volumio[606]: info: CoreCommandRouter::Close All Modals sent Jan 28 21:56:03 volumio ntpd[710]: Soliciting pool server 162.159.200.123 Jan 28 21:56:03 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 28 21:56:03 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 21:56:03 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 28 21:56:04 volumio ntpd[710]: Soliciting pool server 101.42.50.95 Jan 28 21:56:04 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 21:56:04 volumio volumio[606]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 28 21:56:04 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 28 21:56:04 volumio volumio[606]: info: Received Get System Version Jan 28 21:56:04 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 21:56:04 volumio volumio[606]: info: Received Get System Info Jan 28 21:56:04 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 21:56:04 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 21:56:04 volumio volumio[606]: info: Discovery: Getting this device information Jan 28 21:56:04 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:56:04 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:04 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 21:56:05 volumio ntpd[710]: Soliciting pool server 139.199.215.251 Jan 28 21:56:05 volumio ntpd[710]: Soliciting pool server 193.182.111.12 Jan 28 21:56:06 volumio ntpd[710]: Soliciting pool server 193.182.111.143 Jan 28 21:56:06 volumio ntpd[710]: Soliciting pool server 119.28.183.184 Jan 28 21:56:07 volumio ntpd[710]: Soliciting pool server 193.182.111.142 Jan 28 21:56:07 volumio ntpd[710]: Soliciting pool server 84.16.73.33 Jan 28 21:56:08 volumio ntpd[710]: Soliciting pool server 84.16.67.12 Jan 28 21:56:08 volumio ntpd[710]: Soliciting pool server 193.182.111.141 Jan 28 21:56:09 volumio ntpd[710]: Soliciting pool server 108.59.2.24 Jan 28 21:56:09 volumio ntpd[710]: Soliciting pool server 2402:4e00:c050:2400:32d6:6914:76b4:1 Jan 28 21:56:10 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:56:10 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:10 volumio ntpd[710]: Soliciting pool server 162.159.200.1 Jan 28 21:56:12 volumio ntpd[710]: receive: Unexpected origin timestamp 0xed2493fb.65eed0ec does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xed2493fc.3c238d02 Jan 28 21:56:12 volumio ntpd[710]: receive: Unexpected origin timestamp 0xed2493fb.65faa2a0 does not match aorg 0000000000.00000000 from server@84.16.73.33 xmt 0xed2493fc.58539966 Jan 28 21:56:13 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 21:56:14 volumio volumio[606]: info: CURURI: music-library/NAS Jan 28 21:56:14 volumio volumio[606]: info: Preload queue cleared Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 21:56:20 volumio volumio[606]: info: Retrieving Cloud Streaming UI Jan 28 21:56:20 volumio volumio[606]: info: Getting Tidal Cloud Configuration Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 21:56:20 volumio volumio[606]: info: Getting Qobuz Cloud Configuration Jan 28 21:56:20 volumio volumio[606]: info: Asking plugin for UI Config Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 21:56:20 volumio volumio[606]: info: Getting Spotify Cloud Configuration Jan 28 21:56:20 volumio volumio[606]: info: Asking plugin for UI Config Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 21:56:20 volumio volumio[606]: info: Saving Spotify Acccount Jan 28 21:56:20 volumio volumio[606]: info: Got Tidal Cloud Configuration Jan 28 21:56:20 volumio volumio[606]: info: Got it Jan 28 21:56:20 volumio volumio[606]: info: Got it Jan 28 21:56:20 volumio volumio[606]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 21:56:20 volumio volumio[606]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 21:56:20 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 28 21:56:21 volumio volumio[606]: info: CoreCommandRouter::volumioGetState Jan 28 21:56:21 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:24 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 28 21:56:24 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 21:56:24 volumio sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/usb Jan 28 21:56:24 volumio sudo[1760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 21:56:24 volumio sudo[1760]: pam_unix(sudo:session): session closed for user root Jan 28 21:56:24 volumio systemd[1]: mnt-NAS-usb.mount: Succeeded. Jan 28 21:56:24 volumio volumio[606]: info: Share usb successfully unmounted Jan 28 21:56:25 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 28 21:56:27 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 28 21:56:27 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:56:27 volumio volumio[606]: info: Jan 28 21:56:27 volumio volumio[606]: ---------------------------- MPD announces state update: update Jan 28 21:56:27 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:56:27 volumio volumio[606]: info: Jan 28 21:56:27 volumio volumio[606]: ---------------------------- MPD announces state update: update Jan 28 21:56:27 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:56:27 volumio volumio[606]: info: Jan 28 21:56:27 volumio volumio[606]: ---------------------------- MPD announces state update: update Jan 28 21:56:27 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:56:27 volumio volumio[606]: info: Jan 28 21:56:27 volumio volumio[606]: ---------------------------- MPD announces state update: update Jan 28 21:56:27 volumio volumio[606]: info: ControllerMpd::getState Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand status Jan 28 21:56:27 volumio volumio[606]: info: sendMpdCommand status took 15 milliseconds Jan 28 21:56:27 volumio volumio[606]: info: sendMpdCommand status took 13 milliseconds Jan 28 21:56:27 volumio volumio[606]: info: sendMpdCommand status took 12 milliseconds Jan 28 21:56:27 volumio volumio[606]: info: sendMpdCommand status took 10 milliseconds Jan 28 21:56:27 volumio volumio[606]: info: sendMpdCommand status took 10 milliseconds Jan 28 21:56:27 volumio volumio[606]: info: sendMpdCommand status took 8 milliseconds Jan 28 21:56:27 volumio volumio[606]: info: sendMpdCommand status took 8 milliseconds Jan 28 21:56:27 volumio volumio[606]: info: sendMpdCommand status took 6 milliseconds Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:56:27 volumio volumio[606]: info: Command Router : Notfying DB Updatetrue Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:56:27 volumio volumio[606]: info: Command Router : Notfying DB Updatetrue Jan 28 21:56:27 volumio volumio[606]: info: CoreCommandRouter::Close All Modals sent Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:56:27 volumio volumio[606]: info: Command Router : Notfying DB Updatetrue Jan 28 21:56:27 volumio volumio[606]: info: CoreCommandRouter::Close All Modals sent Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:56:27 volumio volumio[606]: info: Command Router : Notfying DB Updatetrue Jan 28 21:56:27 volumio volumio[606]: info: CoreCommandRouter::Close All Modals sent Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::parseState Jan 28 21:56:27 volumio volumio[606]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 21:56:27 volumio volumio[606]: info: [1769608587995] Database update started Jan 28 21:56:28 volumio volumio[606]: info: sendMpdCommand playlistinfo took 188 milliseconds Jan 28 21:56:28 volumio volumio[606]: info: sendMpdCommand playlistinfo took 155 milliseconds Jan 28 21:56:28 volumio volumio[606]: info: sendMpdCommand playlistinfo took 122 milliseconds Jan 28 21:56:28 volumio volumio[606]: info: sendMpdCommand playlistinfo took 82 milliseconds Jan 28 21:56:28 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:56:28 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:56:28 volumio volumio[606]: verbose: ControllerMpd::parseTrackInfo Jan 28 21:56:28 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:56:28 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:56:28 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:28 volumio volumio[606]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22971,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:56:28 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::syncState stateService pause Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus pause Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:56:28 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:28 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 21:56:28 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::stPlaybackTimer Jan 28 21:56:28 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:56:28 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:56:28 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:28 volumio volumio[606]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22971,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:56:28 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::syncState stateService pause Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus pause Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:56:28 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:28 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::stPlaybackTimer Jan 28 21:56:28 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:56:28 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:56:28 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:28 volumio volumio[606]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22971,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:56:28 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::syncState stateService pause Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus pause Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:56:28 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:28 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::stPlaybackTimer Jan 28 21:56:28 volumio volumio[606]: info: ControllerMpd::pushState Jan 28 21:56:28 volumio volumio[606]: info: CoreCommandRouter::servicePushState Jan 28 21:56:28 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:28 volumio volumio[606]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":22971,"duration":302,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??¶_?????ij?վ (Remix)","artist":"DJ","album":null,"uri":"NAS/2T/歌曲/03 发烧女声精选200首/003.孙露-离别的车站.wav","trackType":"wav"} Jan 28 21:56:28 volumio volumio[606]: verbose: CURRENT POSITION 0 Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::syncState stateService pause Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::syncState currentStatus pause Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::pushState Jan 28 21:56:28 volumio volumio[606]: info: CorePlayQueue::getTrack 0 Jan 28 21:56:28 volumio volumio[606]: info: CoreCommandRouter::volumioPushState Jan 28 21:56:28 volumio volumio[606]: info: CoreStateMachine::stPlaybackTimer Jan 28 21:56:28 volumio volumio[606]: info: ------------------------------ 408ms Jan 28 21:56:28 volumio volumio[606]: info: ------------------------------ 404ms Jan 28 21:56:28 volumio volumio[606]: info: ------------------------------ 403ms Jan 28 21:56:28 volumio volumio[606]: info: ------------------------------ 402ms Jan 28 21:56:28 volumio volumio[606]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 21:56:32 volumio volumio[606]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 21:56:32 volumio volumio[606]: Error: dns service error: unknown Jan 28 21:56:32 volumio volumio[606]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jan 28 21:56:32 volumio volumio[606]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jan 28 21:56:32 volumio volumio[606]: errorCode: -65537 Jan 28 21:56:32 volumio volumio[606]: } Jan 28 21:56:32 volumio volumio[606]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 21:56:32 volumio sudo[1800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-28 21:55 Jan 28 21:56:32 volumio sudo[1800]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"