-- Logs begin at Tue 2025-05-27 18:31:45 CST, end at Tue 2025-05-27 18:33:49 CST. -- May 27 18:32:00 volumio ntpd[987]: Soliciting pool server 193.182.111.12 May 27 18:32:00 volumio volumio[698]: info: Loading plugin "network"... May 27 18:32:00 volumio volumio[698]: info: Refreshing Cached IP Addresses May 27 18:32:00 volumio volumio[698]: info: Loading plugin "services"... May 27 18:32:00 volumio volumio[698]: info: Loading plugin "alsa_controller"... May 27 18:32:00 volumio volumio[698]: warn: Unable to locate the audio output device Dummy. Please configure a valid output device. May 27 18:32:00 volumio volumio[698]: Invalid card number. May 27 18:32:00 volumio volumio[698]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer May 27 18:32:00 volumio sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 18:32:00 volumio sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:00 volumio sudo[1001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 27 18:32:00 volumio sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 18:32:00 volumio sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:00 volumio sudo[1001]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:00 volumio sudo[997]: pam_unix(sudo:session): session closed for user root May 27 18:32:00 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 18:32:00 volumio sudo[999]: pam_unix(sudo:session): session closed for user root May 27 18:32:00 volumio sudo[1001]: pam_unix(sudo:session): session closed for user root May 27 18:32:00 volumio volumio[698]: Invalid card number. May 27 18:32:00 volumio volumio[698]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 27 18:32:01 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 18:32:01 volumio volumio[698]: info: Loading plugin "wizard"... May 27 18:32:01 volumio volumio[698]: info: Loading plugin "networkfs"... May 27 18:32:01 volumio ntpd[987]: Soliciting pool server 45.76.221.157 May 27 18:32:01 volumio ntpd[987]: Soliciting pool server 202.118.1.130 May 27 18:32:01 volumio sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Troy,password=Ivan-0330,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.10.215/Multimedia/Music /mnt/NAS/Music May 27 18:32:01 volumio sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:01 volumio sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Troy,password=Ivan-0330,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.10.215/Multimedia/My Collections /mnt/NAS/My_Collections May 27 18:32:01 volumio sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:01 volumio sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Troy,password=Ivan-0330,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.10.215/My Collections 2 /mnt/NAS/My_Collections_2 May 27 18:32:01 volumio sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:01 volumio kernel: FS-Cache: Loaded May 27 18:32:01 volumio sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Troy,password=Ivan-0330,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.10.215/My Collections 3 /mnt/NAS/My_Collections_3 May 27 18:32:01 volumio sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:01 volumio kernel: Key type dns_resolver registered May 27 18:32:01 volumio volumio[698]: info: Starting Udev Watcher for removable devices May 27 18:32:01 volumio sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Troy,password=Ivan-0330,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.10.215/My Collections 4 /mnt/NAS/My_Collections_4 May 27 18:32:01 volumio sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:01 volumio volumio[698]: info: Ignoring mount for partition: 3602c5f8-cdbb-40a6-9b0b-c44c94fdd324 May 27 18:32:01 volumio volumio[698]: info: Ignoring mount for partition: boot May 27 18:32:01 volumio volumio[698]: info: Ignoring mount for partition: volumio May 27 18:32:01 volumio volumio[698]: info: Ignoring mount for partition: volumio_data May 27 18:32:01 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 27 18:32:01 volumio volumio[698]: info: Loading plugin "volumio_command_line_client"... May 27 18:32:01 volumio kernel: Key type cifs.spnego registered May 27 18:32:01 volumio kernel: Key type cifs.idmap registered May 27 18:32:01 volumio kernel: CIFS: Attempting to mount //192.168.10.215/Multimedia/My Collections May 27 18:32:01 volumio volumio[698]: info: Loading plugin "upnp"... May 27 18:32:01 volumio volumio[698]: info: [1748341921584] Starting Upmpd Daemon May 27 18:32:01 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 27 18:32:01 volumio volumio[698]: info: Loading plugin "my_music"... May 27 18:32:01 volumio volumio[698]: info: Loading plugin "mpd"... May 27 18:32:01 volumio nmbd[629]: [2025/05/27 18:32:01.791868, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 27 18:32:01 volumio nmbd[629]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 27 18:32:01 volumio systemd[1]: Started Samba NMB Daemon. May 27 18:32:01 volumio systemd[1]: Starting Samba Winbind Daemon... May 27 18:32:01 volumio nmbd[629]: [2025/05/27 18:32:01.823745, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 27 18:32:01 volumio nmbd[629]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.43 for name WORKGROUP<1d>. May 27 18:32:01 volumio nmbd[629]: This response was from IP 192.168.10.201, reporting an IP address of 192.168.10.201. May 27 18:32:01 volumio sudo[1022]: pam_unix(sudo:session): session closed for user root May 27 18:32:01 volumio kernel: CIFS: Attempting to mount //192.168.10.215/Multimedia/Music May 27 18:32:01 volumio kernel: CIFS: Attempting to mount //192.168.10.215/My Collections 4 May 27 18:32:01 volumio sudo[1020]: pam_unix(sudo:session): session closed for user root May 27 18:32:01 volumio kernel: CIFS: Attempting to mount //192.168.10.215/My Collections 2 May 27 18:32:01 volumio sudo[1042]: pam_unix(sudo:session): session closed for user root May 27 18:32:01 volumio sudo[1025]: pam_unix(sudo:session): session closed for user root May 27 18:32:01 volumio kernel: CIFS: Attempting to mount //192.168.10.215/My Collections 3 May 27 18:32:01 volumio sudo[1028]: pam_unix(sudo:session): session closed for user root May 27 18:32:02 volumio ntpd[987]: Soliciting pool server 193.182.111.143 May 27 18:32:02 volumio ntpd[987]: Soliciting pool server 108.59.2.24 May 27 18:32:02 volumio winbindd[1080]: [2025/05/27 18:32:02.487727, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 27 18:32:02 volumio winbindd[1080]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 27 18:32:02 volumio volumio[698]: info: Loading plugin "upnp_browser"... May 27 18:32:02 volumio winbindd[1080]: [2025/05/27 18:32:02.518391, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 27 18:32:02 volumio systemd[1]: Started Samba Winbind Daemon. May 27 18:32:02 volumio winbindd[1080]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 27 18:32:02 volumio systemd[1]: Starting Samba SMB Daemon... May 27 18:32:02 volumio volumio-remote-updater[446]: [2025-05-27 18:32:02] [connect] Successful connection May 27 18:32:03 volumio ntpd[987]: Soliciting pool server 95.111.202.5 May 27 18:32:03 volumio ntpd[987]: Soliciting pool server 162.159.200.123 May 27 18:32:03 volumio ntpd[987]: Soliciting pool server 162.159.200.1 May 27 18:32:03 volumio smbd[1085]: [2025/05/27 18:32:03.230617, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 27 18:32:03 volumio smbd[1085]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 27 18:32:03 volumio systemd[1]: Started Samba SMB Daemon. May 27 18:32:03 volumio systemd[1]: Reached target Multi-User System. May 27 18:32:03 volumio systemd[1]: Reached target Graphical Interface. May 27 18:32:03 volumio volumio[698]: info: Loading plugin "alarm-clock"... May 27 18:32:03 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 27 18:32:03 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 27 18:32:03 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 27 18:32:03 volumio systemd[1]: Startup finished in 4.517s (firmware) + 8.586s (loader) + 22.412s (kernel) + 19.283s (userspace) = 54.801s. May 27 18:32:03 volumio volumio[698]: info: Loading plugin "airplay_emulation"... May 27 18:32:03 volumio volumio[698]: info: Starting Shairport Sync May 27 18:32:03 volumio volumio[698]: info: Loading plugin "last_100"... May 27 18:32:03 volumio volumio[698]: info: Loading plugin "webradio"... May 27 18:32:03 volumio volumio[698]: info: Loading plugin "i2s_dacs"... May 27 18:32:03 volumio volumio[698]: info: I2S DAC not set, start Auto-detection May 27 18:32:03 volumio volumio[698]: info: Loading plugin "volumiodiscovery"... May 27 18:32:03 volumio volumio[698]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 27 18:32:03 volumio volumio[698]: *** WARNING *** Please fix your application to use the native API of Avahi! May 27 18:32:03 volumio node[698]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 27 18:32:03 volumio volumio[698]: *** WARNING *** For more information see May 27 18:32:03 volumio volumio[698]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 27 18:32:03 volumio volumio[698]: *** WARNING *** Please fix your application to use the native API of Avahi! May 27 18:32:03 volumio volumio[698]: *** WARNING *** For more information see May 27 18:32:03 volumio node[698]: *** WARNING *** Please fix your application to use the native API of Avahi! May 27 18:32:03 volumio node[698]: *** WARNING *** For more information see May 27 18:32:03 volumio node[698]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 27 18:32:03 volumio node[698]: *** WARNING *** Please fix your application to use the native API of Avahi! May 27 18:32:03 volumio node[698]: *** WARNING *** For more information see May 27 18:32:03 volumio volumio[698]: info: Applying required configuration parameters for plugin volumiodiscovery May 27 18:32:03 volumio volumio[698]: info: Discovery: Started advertising with name: Volumio May 27 18:32:04 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 27 18:32:04 volumio volumio[698]: info: Loading plugin "autostart"... May 27 18:32:04 volumio ntpd[987]: Soliciting pool server 202.118.1.81 May 27 18:32:04 volumio ntpd[987]: Soliciting pool server 45.76.221.157 May 27 18:32:04 volumio volumio[698]: info: Applying required configuration parameters for plugin autostart May 27 18:32:04 volumio volumio[698]: info: AutoStart - onVolumioStart - read config.json May 27 18:32:04 volumio volumio[698]: info: Loading plugin "peppy_screensaver"... May 27 18:32:05 volumio ntpd[987]: Soliciting pool server 2001:470:1d:281::123 May 27 18:32:05 volumio volumio[698]: info: Applying required configuration parameters for plugin peppy_screensaver May 27 18:32:05 volumio volumio[698]: info: Loading plugin "outputs"... May 27 18:32:05 volumio volumio[698]: info: Loading plugin "albumart"... May 27 18:32:05 volumio volumio[698]: info: Plugin example_plugin is not enabled May 27 18:32:05 volumio volumio[698]: info: Loading plugin "inputs"... May 27 18:32:05 volumio volumio[698]: info: Loading plugin "updater_comm"... May 27 18:32:05 volumio volumio[698]: Forking 1 albumart workers May 27 18:32:05 volumio volumio[698]: info: Plugin mpdemulation is not enabled May 27 18:32:05 volumio volumio[698]: info: Loading plugin "rest_api"... May 27 18:32:05 volumio volumio[698]: info: Loading plugin "websocket"... May 27 18:32:05 volumio volumio[698]: info: Loading plugin "RoonBridge"... May 27 18:32:05 volumio dbus-daemon[438]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.9' (uid=0 pid=930 comm="/usr/lib/chromium/chromium --show-component-extens") May 27 18:32:05 volumio systemd[1]: Starting Bluetooth service... May 27 18:32:06 volumio volumio[698]: Starting albumart workers May 27 18:32:06 volumio bluetoothd[1128]: Bluetooth daemon 5.50 May 27 18:32:06 volumio kernel: Bluetooth: Core ver 2.22 May 27 18:32:06 volumio kernel: NET: Registered PF_BLUETOOTH protocol family May 27 18:32:06 volumio kernel: Bluetooth: HCI device and connection manager initialized May 27 18:32:06 volumio kernel: Bluetooth: HCI socket layer initialized May 27 18:32:06 volumio kernel: Bluetooth: L2CAP socket layer initialized May 27 18:32:06 volumio kernel: Bluetooth: SCO socket layer initialized May 27 18:32:06 volumio bluetoothd[1128]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 27 18:32:06 volumio dbus-daemon[438]: [system] Successfully activated service 'org.bluez' May 27 18:32:06 volumio systemd[1]: Started Bluetooth service. May 27 18:32:06 volumio bluetoothd[1128]: Starting SDP server May 27 18:32:06 volumio bluetoothd[1128]: Excluding (cli) sap May 27 18:32:06 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 27 18:32:06 volumio kernel: Bluetooth: BNEP filters: protocol multicast May 27 18:32:06 volumio kernel: Bluetooth: BNEP socket layer initialized May 27 18:32:06 volumio bluetoothd[1128]: Bluetooth management interface 1.22 initialized May 27 18:32:06 volumio dbus-daemon[438]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=1128 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 27 18:32:06 volumio systemd[1]: Starting Hostname Service... May 27 18:32:06 volumio volumio[698]: info: Applying required configuration parameters for plugin RoonBridge May 27 18:32:06 volumio volumio[698]: info: Loading i18n strings for locale zh May 27 18:32:06 volumio dbus-daemon[438]: [system] Successfully activated service 'org.freedesktop.hostname1' May 27 18:32:06 volumio systemd[1]: Started Hostname Service. May 27 18:32:06 volumio volumio[698]: Updating browse sources language May 27 18:32:06 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::initPlayerControls May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 18:32:07 volumio volumio[698]: Express server listening on port 3000 May 27 18:32:07 volumio volumio[698]: [Metrics] WebUI: 12s 269.11ms May 27 18:32:07 volumio volumio[698]: info: Setting Device type: x86 May 27 18:32:07 volumio volumio[698]: info: CoreStateMachine::resetVolumioState May 27 18:32:07 volumio volumio[698]: info: CoreStateMachine::getcurrentVolume May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::volumioRetrievevolume May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 27 18:32:07 volumio volumio[698]: info: CoreStateMachine::getcurrentVolume May 27 18:32:07 volumio volumio[698]: info: CoreCommandRouter::volumioRetrievevolume May 27 18:32:07 volumio volumio[698]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 27 18:32:07 volumio volumio[698]: info: Completed loading Core Plugins May 27 18:32:07 volumio volumio[698]: info: Preparing to generate the ALSA configuration file May 27 18:32:07 volumio volumio[698]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 27 18:32:07 volumio volumio[698]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 27 18:32:07 volumio volumio[698]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 27 18:32:07 volumio volumio[698]: wlan0 Interface doesn't support scanning. May 27 18:32:07 volumio volumio[698]: info: Cannot use regular scanning, forcing with ap-force May 27 18:32:08 volumio sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 27 18:32:08 volumio sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:08 volumio sudo[1225]: pam_unix(sudo:session): session closed for user root May 27 18:32:08 volumio volumio[698]: command failed: No such device (-19) May 27 18:32:08 volumio volumio[698]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 27 18:32:08 volumio volumio[698]: command failed: No such device (-19) May 27 18:32:08 volumio volumio[698]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 27 18:32:08 volumio volumio[698]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 27 18:32:08 volumio volumio[698]: info: Reading ALSA contributions from plugins. May 27 18:32:08 volumio volumio[698]: info: VolumeController:: Volume=undefined Mute =false May 27 18:32:08 volumio volumio[698]: info: CoreStateMachine::pushState May 27 18:32:08 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::volumioPushState May 27 18:32:08 volumio volumio[698]: info: CoreStateMachine::updateTrackBlock May 27 18:32:08 volumio volumio[698]: info: CorePlayQueue::getTrackBlock May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::volumioRetrievevolume May 27 18:32:08 volumio volumio[698]: info: VolumeController:: Volume=undefined Mute =false May 27 18:32:08 volumio volumio[698]: info: CoreStateMachine::pushState May 27 18:32:08 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::volumioPushState May 27 18:32:08 volumio volumio[698]: info: CoreStateMachine::updateTrackBlock May 27 18:32:08 volumio volumio[698]: info: CorePlayQueue::getTrackBlock May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::volumioRetrievevolume May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:08 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:08 volumio volumio[698]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 27 18:32:08 volumio volumio[698]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 27 18:32:08 volumio volumio[698]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 27 18:32:08 volumio volumio[698]: info: Reloading queue from file May 27 18:32:08 volumio volumio[698]: info: VolumeController:: Volume=undefined Mute =false May 27 18:32:08 volumio volumio[698]: info: CoreStateMachine::pushState May 27 18:32:08 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::volumioPushState May 27 18:32:08 volumio volumio[698]: info: CoreStateMachine::setRepeat true single undefined May 27 18:32:08 volumio volumio[698]: info: CoreStateMachine::pushState May 27 18:32:08 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::volumioPushState May 27 18:32:08 volumio volumio[698]: info: CoreStateMachine::setRandom null May 27 18:32:08 volumio volumio[698]: info: CoreStateMachine::pushState May 27 18:32:08 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::volumioPushState May 27 18:32:08 volumio volumio[698]: info: VolumeController:: Volume=undefined Mute =false May 27 18:32:08 volumio volumio[698]: info: CoreStateMachine::pushState May 27 18:32:08 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::volumioPushState May 27 18:32:08 volumio volumio[698]: info: Asound.conf file unchanged, so no further update is needed May 27 18:32:08 volumio volumio[698]: info: Output device has changed, restarting MPD May 27 18:32:08 volumio sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 18:32:08 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:08 volumio volumio[698]: info: Output device has changed, restarting Shairport Sync May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:08 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root May 27 18:32:08 volumio sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 18:32:08 volumio sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:08 volumio systemd[1]: Listening on mpd.socket. May 27 18:32:08 volumio volumio[698]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 18:32:08 volumio volumio[698]: info: ___________ START PLUGINS ___________ May 27 18:32:08 volumio volumio[698]: info: ControllerMpd::onStart: Initializing MPD May 27 18:32:08 volumio volumio[698]: info: Creating MPD Configuration file May 27 18:32:08 volumio systemd[1]: Starting Music Player Daemon... May 27 18:32:08 volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 18:32:08 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 27 18:32:08 volumio volumio[698]: info: [1748341928940] CoreMusicLibrary::Adding element 媒体服务器 May 27 18:32:08 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 18:32:08 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root May 27 18:32:09 volumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 18:32:09 volumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:09 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 27 18:32:09 volumio systemd[1]: mpd.service: Succeeded. May 27 18:32:09 volumio systemd[1]: Stopped Music Player Daemon. May 27 18:32:09 volumio systemd[1]: Starting Music Player Daemon... May 27 18:32:09 volumio volumio[698]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:09 volumio volumio[698]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 27 18:32:09 volumio volumio[698]: info: [1748341929203] CoreMusicLibrary::Adding element Last_100 May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 27 18:32:09 volumio volumio[698]: info: [1748341929210] CoreMusicLibrary::Adding element Webradio May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 18:32:09 volumio volumio[698]: info: AutoStart - onStart May 27 18:32:09 volumio volumio[698]: info: Loading i18n strings for locale zh May 27 18:32:09 volumio volumio[698]: info: /tmp/myfifo created May 27 18:32:09 volumio volumio[698]: info: /tmp/myfifosa created May 27 18:32:09 volumio sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 May 27 18:32:09 volumio sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:09 volumio sudo[1297]: pam_unix(sudo:session): session closed for user root May 27 18:32:09 volumio volumio[698]: info: snd-dummy loaded May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:09 volumio volumio[698]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 27 18:32:09 volumio volumio[698]: info: Volumio Calling Home May 27 18:32:09 volumio sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 27 18:32:09 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:09 volumio systemd[1]: Started RoonBridge. May 27 18:32:09 volumio sudo[1309]: pam_unix(sudo:session): session closed for user root May 27 18:32:09 volumio volumio[698]: info: Discovery: adding a99547a7-f380-4a98-b83c-927c021eec55 May 27 18:32:09 volumio volumio[698]: info: Discovery: Found device Volumio May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:09 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:09 volumio volumio[698]: info: MPD Permissions set May 27 18:32:09 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:09 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:09 volumio volumio[698]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:10 volumio sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 27 18:32:10 volumio sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:10 volumio sudo[1328]: pam_unix(sudo:session): session closed for user root May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:10 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile May 27 18:32:10 volumio volumio[698]: info: Volumio called home May 27 18:32:10 volumio sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 18:32:10 volumio sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:10 volumio volumio[698]: info: Starting Shairport Sync May 27 18:32:10 volumio sudo[1343]: pam_unix(sudo:session): session closed for user root May 27 18:32:10 volumio volumio[698]: info: Starting Shairport Sync May 27 18:32:10 volumio sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 18:32:10 volumio sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:10 volumio volumio[698]: info: Starting Shairport Sync May 27 18:32:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 27 18:32:11 volumio systemd[1]: shairport-sync.service: Succeeded. May 27 18:32:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 27 18:32:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 27 18:32:11 volumio sudo[1346]: pam_unix(sudo:session): session closed for user root May 27 18:32:11 volumio volumio[698]: info: Starting Shairport Sync May 27 18:32:11 volumio sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 18:32:11 volumio sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:11 volumio sudo[1352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 18:32:11 volumio sudo[1352]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 27 18:32:11 volumio systemd[1]: shairport-sync.service: Succeeded. May 27 18:32:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 27 18:32:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 27 18:32:11 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 27 18:32:11 volumio sudo[1349]: pam_unix(sudo:session): session closed for user root May 27 18:32:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 27 18:32:11 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 27 18:32:11 volumio systemd[1]: shairport-sync.service: Succeeded. May 27 18:32:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 27 18:32:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 27 18:32:11 volumio sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 27 18:32:11 volumio sudo[1352]: pam_unix(sudo:session): session closed for user root May 27 18:32:11 volumio sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 27 18:32:11 volumio systemd[1]: shairport-sync.service: Succeeded. May 27 18:32:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 27 18:32:11 volumio sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 18:32:11 volumio sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 27 18:32:11 volumio sudo[1358]: pam_unix(sudo:session): session closed for user root May 27 18:32:11 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 27 18:32:11 volumio systemd[1]: mpd.service: Succeeded. May 27 18:32:11 volumio systemd[1]: Stopped Music Player Daemon. May 27 18:32:11 volumio systemd[1]: Starting Music Player Daemon... May 27 18:32:11 volumio volumio[698]: info: Shairport-Sync Started May 27 18:32:11 volumio volumio[698]: Error adding Membership: Error: addMembership EINVAL May 27 18:32:11 volumio volumio[698]: info: Shairport-Sync Started May 27 18:32:11 volumio volumio[698]: info: Shairport-Sync Started May 27 18:32:11 volumio volumio[698]: info: MPD Permissions set May 27 18:32:11 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:11 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:11 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:11 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 27 18:32:11 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:11 volumio volumio[698]: info: Shairport-Sync Started May 27 18:32:11 volumio RoonBridge[1314]: 00:00:00.021 Warn: get lock file path: /tmp/.rnbgem0- May 27 18:32:11 volumio sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 18:32:11 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:11 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root May 27 18:32:11 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:11 volumio sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 18:32:11 volumio sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:11 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:11 volumio sudo[1381]: pam_unix(sudo:session): session closed for user root May 27 18:32:12 volumio sudo[1386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 27 18:32:12 volumio sudo[1386]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:12 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 27 18:32:12 volumio sudo[1386]: pam_unix(sudo:session): session closed for user root May 27 18:32:12 volumio volumio[698]: info: Upmpdcli Daemon Started May 27 18:32:12 volumio RoonBridge[1314]: 00:00:00.840 Trace: [childprocess] using unix child process May 27 18:32:12 volumio RoonBridge[1314]: 00:00:00.985 Debug: PathForResource, filename: ../.update May 27 18:32:12 volumio sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 18:32:12 volumio sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:12 volumio sudo[1390]: pam_unix(sudo:session): session closed for user root May 27 18:32:12 volumio sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 18:32:12 volumio sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:12 volumio RoonBridge[1314]: 00:00:01.087 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update May 27 18:32:12 volumio sudo[1393]: pam_unix(sudo:session): session closed for user root May 27 18:32:12 volumio RoonBridge[1314]: 00:00:01.094 Debug: PathForResource, filename: RAATServer May 27 18:32:12 volumio RoonBridge[1314]: 00:00:01.094 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer May 27 18:32:12 volumio RoonBridge[1314]: 00:00:01.099 Debug: PathForResource, filename: RoonBridgeHelper May 27 18:32:12 volumio RoonBridge[1314]: 00:00:01.099 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 27 18:32:12 volumio RoonBridge[1314]: Initializing May 27 18:32:12 volumio volumio[698]: verbose: New Socket.io Connection to 192.168.10.43 from 192.168.10.24 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3.1 Safari/605.1.15 Total Clients: 3 May 27 18:32:12 volumio RoonBridge[1314]: 00:00:01.201 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 27 18:32:12 volumio RoonBridge[1314]: 00:00:01.318 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer May 27 18:32:12 volumio sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 18:32:12 volumio sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:12 volumio sudo[1403]: pam_unix(sudo:session): session closed for user root May 27 18:32:12 volumio sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 18:32:12 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:13 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root May 27 18:32:13 volumio volumio[698]: verbose: New Socket.io Connection to 192.168.10.43 from 192.168.10.24 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3.1 Safari/605.1.15 Total Clients: 4 May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 27 18:32:13 volumio RoonBridge[1314]: Not Running (.o) May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::volumioGetVisibleSources May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:13 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 27 18:32:13 volumio volumio[698]: info: Received Get System Info May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 18:32:13 volumio volumio[698]: info: Discovery: Getting this device information May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:13 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:13 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:13 volumio volumio[698]: info: Listing playlists May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 27 18:32:13 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 27 18:32:14 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:14 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:14 volumio mpd[1368]: May 27 18:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 27 18:32:14 volumio RoonBridge[1314]: Running May 27 18:32:14 volumio volumio[698]: info: VolumeController::SetAlsaVolume0 May 27 18:32:14 volumio volumio[698]: info: CoreStateMachine::pushState May 27 18:32:14 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:14 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 18:32:14 volumio volumio[698]: info: CoreCommandRouter::volumioPushState May 27 18:32:14 volumio volumio[698]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 May 27 18:32:14 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 27 18:32:14 volumio volumio[698]: info: Received Get System Info May 27 18:32:14 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 18:32:14 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 18:32:14 volumio volumio[698]: info: Discovery: Getting this device information May 27 18:32:14 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:14 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:14 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 18:32:15 volumio RoonBridge[1314]: 00:00:00.068 Warn: get lock file path: /tmp/.rnbhgem0- May 27 18:32:17 volumio volumio-remote-updater[446]: [2025-05-27 18:32:17] [connect] Successful connection May 27 18:32:17 volumio volumio-remote-updater[446]: [2025-05-27 18:32:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748341937 101 May 27 18:32:17 volumio volumio[698]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 May 27 18:32:17 volumio volumio[698]: 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: 6 May 27 18:32:17 volumio systemd[1]: systemd-fsckd.service: Succeeded. May 27 18:32:18 volumio volumio[698]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 18:32:18 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 27 18:32:18 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 27 18:32:18 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 27 18:32:18 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 27 18:32:18 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 27 18:32:18 volumio volumio[698]: info: CoreCommandRouter::volumioGetBrowseSources May 27 18:32:18 volumio volumio[698]: info: CoreCommandRouter::volumioGetBrowseSources May 27 18:32:18 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 18:32:18 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 27 18:32:19 volumio volumio[698]: info: AutoStart - Plugin is starting May 27 18:32:19 volumio volumio[698]: info: CoreCommandRouter::volumioGetQueue May 27 18:32:19 volumio volumio[698]: info: CoreStateMachine::getQueue May 27 18:32:19 volumio volumio[698]: info: CorePlayQueue::getQueue May 27 18:32:19 volumio volumio[698]: info: AutoStart - start playing May 27 18:32:19 volumio volumio[698]: info: AutoStart - start playing with no specific position May 27 18:32:19 volumio volumio[698]: info: CoreCommandRouter::volumioPlay May 27 18:32:19 volumio volumio[698]: info: CoreStateMachine::play index 0 May 27 18:32:19 volumio volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:32:19 volumio volumio[698]: info: CoreStateMachine::stop May 27 18:32:19 volumio volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:32:19 volumio volumio[698]: info: CoreStateMachine::play index undefined May 27 18:32:19 volumio volumio[698]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 18:32:19 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:19 volumio volumio[698]: info: CoreStateMachine::startPlaybackTimer May 27 18:32:19 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:19 volumio volumio[698]: verbose: ControllerMpd::clearAddPlayTracks USB/music/惠威试音碟 (人声篇)/01.月光小夜曲.wav May 27 18:32:19 volumio volumio[698]: verbose: ControllerMpd::sendMpdCommand stop May 27 18:32:19 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:19 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:19 volumio systemd[1]: Started Music Player Daemon. May 27 18:32:19 volumio volumio[698]: Upnp client error: Error: This socket has been ended by the other party May 27 18:32:19 volumio sudo[1266]: pam_unix(sudo:session): session closed for user root May 27 18:32:19 volumio sudo[1277]: pam_unix(sudo:session): session closed for user root May 27 18:32:19 volumio sudo[1365]: pam_unix(sudo:session): session closed for user root May 27 18:32:20 volumio volumio[698]: info: Completed starting Core Plugins May 27 18:32:20 volumio volumio[698]: info: ------------------------------------------- May 27 18:32:20 volumio volumio[698]: info: ----- MyVolumio plugins startup ---- May 27 18:32:20 volumio volumio[698]: info: ------------------------------------------- May 27 18:32:20 volumio volumio[698]: info: [MyVolumio PluginManager] Fetching plans data.... May 27 18:32:20 volumio volumio[698]: info: MPD running with PID1368 May 27 18:32:20 volumio volumio[698]: ,establishing connection May 27 18:32:20 volumio volumio[698]: error: MPD error: The expression evaluated to a falsy value: May 27 18:32:20 volumio volumio[698]: assert.ok(self.idling) May 27 18:32:20 volumio volumio[698]: error: The expression evaluated to a falsy value: May 27 18:32:20 volumio volumio[698]: assert.ok(self.idling) May 27 18:32:20 volumio volumio[698]: error: MPD error: The expression evaluated to a falsy value: May 27 18:32:20 volumio volumio[698]: assert.ok(self.idling) May 27 18:32:20 volumio volumio[698]: error: The expression evaluated to a falsy value: May 27 18:32:20 volumio volumio[698]: assert.ok(self.idling) May 27 18:32:20 volumio volumio[698]: error: MPD error: The expression evaluated to a falsy value: May 27 18:32:20 volumio volumio[698]: assert.ok(self.idling) May 27 18:32:20 volumio volumio[698]: error: The expression evaluated to a falsy value: May 27 18:32:20 volumio volumio[698]: assert.ok(self.idling) May 27 18:32:20 volumio volumio[698]: error: updateQueue error: null May 27 18:32:21 volumio volumio[698]: 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: 7 May 27 18:32:22 volumio volumio[1388]: Generating RSA private key, 4096 bit long modulus (2 primes) May 27 18:32:22 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 18:32:23 volumio volumio[1388]: ....................................++++ May 27 18:32:24 volumio volumio[698]: 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 May 27 18:32:25 volumio volumio[1388]: ......................................................++++ May 27 18:32:25 volumio volumio[1388]: e is 65537 (0x010001) May 27 18:32:25 volumio volumio[1388]: writing RSA key May 27 18:32:26 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 27 18:32:26 volumio volumio[698]: 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 May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetVisibleSources May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:27 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 27 18:32:27 volumio volumio[698]: info: Received Get System Info May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 18:32:27 volumio volumio[698]: info: Discovery: Getting this device information May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:27 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:27 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:27 volumio volumio[698]: info: Listing playlists May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 27 18:32:27 volumio volumio[698]: info: Received Get System Info May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 18:32:27 volumio volumio[698]: info: Discovery: Getting this device information May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:27 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:27 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetVisibleSources May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:27 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:27 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 27 18:32:27 volumio volumio[698]: info: Received Get System Info May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 18:32:27 volumio volumio[698]: info: Discovery: Getting this device information May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:27 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 18:32:27 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:27 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:27 volumio volumio[698]: info: Listing playlists May 27 18:32:28 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:28 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 27 18:32:28 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 27 18:32:29 volumio volumio[698]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 27 18:32:29 volumio volumio[698]: info: Adding plugin bluetooth to MyMusic Plugins May 27 18:32:29 volumio volumio[698]: info: Adding plugin multiroom to MyMusic Plugins May 27 18:32:29 volumio volumio[698]: info: Adding plugin metavolumio to MyMusic Plugins May 27 18:32:29 volumio volumio[698]: info: Adding plugin cd_controller to MyMusic Plugins May 27 18:32:29 volumio volumio[698]: info: Adding plugin smart_inputs to MyMusic Plugins May 27 18:32:29 volumio volumio[698]: info: Adding plugin tidalconnect to MyMusic Plugins May 27 18:32:29 volumio volumio[698]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 27 18:32:31 volumio volumio[698]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 27 18:32:31 volumio volumio[698]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 27 18:32:31 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:31 volumio volumio[698]: info: Starting MyVolumio Remote Streaming Endpoints May 27 18:32:31 volumio volumio[698]: info: MyVolumio not started May 27 18:32:31 volumio volumio[698]: info: Initializing device activation check May 27 18:32:31 volumio volumio[698]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 27 18:32:31 volumio volumio[698]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 27 18:32:33 volumio volumio[698]: info: Starting Streaming Service Transparent Proxy May 27 18:32:33 volumio volumio[698]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 27 18:32:33 volumio volumio[698]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 27 18:32:33 volumio volumio[698]: info: Streaming services startup May 27 18:32:33 volumio volumio[698]: info: Starting Streaming Daemon May 27 18:32:33 volumio sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 27 18:32:33 volumio sudo[1697]: pam_unix(sudo:session): session opened for user root by (uid=0) May 27 18:32:33 volumio volumio[698]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 27 18:32:33 volumio sudo[1697]: pam_unix(sudo:session): session closed for user root May 27 18:32:33 volumio volumio[698]: info: CoreCommandRouter::volumioGetQueue May 27 18:32:33 volumio volumio[698]: info: CoreStateMachine::getQueue May 27 18:32:33 volumio volumio[698]: info: CorePlayQueue::getQueue May 27 18:32:33 volumio volumio[698]: error: MPD error: Error: read ECONNRESET May 27 18:32:33 volumio volumio[698]: error: Cannot start Volumio Streaming Daemon May 27 18:32:33 volumio volumio[698]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 27 18:32:33 volumio volumio[698]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 27 18:32:33 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 27 18:32:33 volumio volumio[698]: info: CURURI: music-library May 27 18:32:33 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:33 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:34 volumio volumio[698]: STREAMING PROXY: Starting server on port 3245 May 27 18:32:34 volumio volumio[698]: Node JS runtime: 14 May 27 18:32:36 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:36 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:36 volumio systemd[1]: systemd-hostnamed.service: Succeeded. May 27 18:32:38 volumio volumio[698]: error: updateQueue error: null May 27 18:32:39 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 18:32:39 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:39 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 18:32:39 volumio volumio-remote-updater[446]: No test mode May 27 18:32:39 volumio volumio-remote-updater[446]: No alpha test mode May 27 18:32:39 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 27 18:32:40 volumio volumio[698]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Various Fixes for QOBUZ Connect
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Qobuz Connect Ready
    • \n
\n","title":"Update v3.812","updateavailable":true} May 27 18:32:40 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 27 18:32:40 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 27 18:32:40 volumio volumio[698]: info: CURURI: music-library May 27 18:32:40 volumio volumio[698]: info: Preload queue cleared May 27 18:32:42 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 27 18:32:42 volumio volumio[698]: info: CURURI: music-library/NAS May 27 18:32:42 volumio volumio[698]: info: Preload queue cleared May 27 18:32:44 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:44 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:46 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 27 18:32:46 volumio volumio[698]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 May 27 18:32:46 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:46 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:46 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 27 18:32:46 volumio volumio[698]: info: CURURI: albums:// May 27 18:32:51 volumio volumio[698]: error: MPD error: Error: read ECONNRESET May 27 18:32:51 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:51 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:51 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 27 18:32:51 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 27 18:32:51 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 27 18:32:51 volumio volumio[698]: info: CoreCommandRouter::volumioGetBrowseSources May 27 18:32:51 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 18:32:53 volumio volumio[698]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 27 18:32:53 volumio volumio[698]: info: Completed starting MyVolumio Plugin May 27 18:32:53 volumio volumio[698]: info: BOOT COMPLETED May 27 18:32:53 volumio volumio[698]: [Metrics] CommandRouter: 57s 87.74ms May 27 18:32:53 volumio volumio[698]: info: CoreCommandRouter::volumiosetStartupVolume May 27 18:32:53 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 18:32:53 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 18:32:53 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent May 27 18:32:53 volumio volumio[698]: info: CoreCommandRouter::Close All Modals sent May 27 18:32:54 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 27 18:32:54 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 18:32:54 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 27 18:32:55 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:32:55 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:32:56 volumio volumio[698]: error: updateQueue error: null May 27 18:33:07 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:33:07 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:33:18 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:33:18 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:33:24 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri May 27 18:33:24 volumio volumio[698]: Error Retrieving last played file: Error: /data/laststates.json: ENOENT: no such file or directory, open '/data/laststates.json' May 27 18:33:24 volumio volumio[698]: info: Preload queue cleared May 27 18:33:29 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 27 18:33:29 volumio volumio[698]: info: Preload queue cleared May 27 18:33:29 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:33:29 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:33:31 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 27 18:33:31 volumio volumio[698]: info: Preload queue cleared May 27 18:33:33 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 27 18:33:36 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 27 18:33:37 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 27 18:33:37 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 27 18:33:40 volumio volumio[698]: info: CoreCommandRouter::volumioGetState May 27 18:33:40 volumio volumio[698]: info: CorePlayQueue::getTrack 0 May 27 18:33:41 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 27 18:33:44 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 27 18:33:44 volumio volumio[698]: info: Preload queue cleared May 27 18:33:46 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 27 18:33:46 volumio volumio[698]: info: Preload queue cleared May 27 18:33:48 volumio volumio[698]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 27 18:33:48 volumio volumio[698]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 18:33:48 volumio volumio[698]: TypeError: Cannot read property 'length' of undefined May 27 18:33:48 volumio volumio[698]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 27 18:33:48 volumio volumio[698]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 27 18:33:48 volumio volumio[698]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 27 18:33:48 volumio volumio[698]: at Parser.emit (events.js:400:28) May 27 18:33:48 volumio volumio[698]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 27 18:33:48 volumio volumio[698]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 27 18:33:48 volumio volumio[698]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 27 18:33:48 volumio volumio[698]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 27 18:33:48 volumio volumio[698]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 27 18:33:48 volumio volumio[698]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 27 18:33:48 volumio volumio[698]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 27 18:33:48 volumio volumio[698]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 27 18:33:48 volumio volumio[698]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 27 18:33:48 volumio volumio[698]: at IncomingMessage.emit (events.js:412:35) May 27 18:33:48 volumio volumio[698]: at endReadableNT (internal/streams/readable.js:1333:12) May 27 18:33:48 volumio volumio[698]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 27 18:33:48 volumio volumio[698]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 18:33:49 volumio sudo[1831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-27 18:32 May 27 18:33:49 volumio sudo[1831]: 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"