-- Logs begin at Mon 2026-01-12 10:27:52 CST, end at Mon 2026-01-12 10:29:08 CST. -- Jan 12 10:28:00 volumio volumio[889]: info: Loading plugin "network"... Jan 12 10:28:00 volumio volumio[889]: info: Refreshing Cached IP Addresses Jan 12 10:28:00 volumio volumio[889]: info: Loading plugin "services"... Jan 12 10:28:00 volumio volumio[889]: info: Loading plugin "alsa_controller"... Jan 12 10:28:00 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 12 10:28:00 volumio sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 10:28:00 volumio sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 10:28:00 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:00 volumio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:00 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:00 volumio sudo[1125]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:00 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:00 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:00 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 10:28:00 volumio volumio[889]: info: Loading plugin "wizard"... Jan 12 10:28:00 volumio volumio[889]: info: Loading plugin "networkfs"... Jan 12 10:28:00 volumio volumio[889]: info: Starting Udev Watcher for removable devices Jan 12 10:28:00 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=DGjc1234,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.50.198/music/CD1-210G /mnt/NAS/CD1-210G Jan 12 10:28:00 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:00 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.11 192.168.50.198:/volume1/music/华语流行音乐-375 /mnt/NAS/华语流行音乐-375 Jan 12 10:28:00 volumio volumio[889]: info: Ignoring mount for partition: boot Jan 12 10:28:00 volumio volumio[889]: info: Ignoring mount for partition: volumio Jan 12 10:28:00 volumio volumio[889]: info: Ignoring mount for partition: volumio_data Jan 12 10:28:00 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 10:28:00 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:00 volumio volumio[889]: info: Loading plugin "volumio_command_line_client"... Jan 12 10:28:00 volumio dbus-daemon[665]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.6' (uid=0 pid=1080 comm="/usr/lib/chromium/chromium --show-component-extens") Jan 12 10:28:00 volumio volumio[889]: info: Loading plugin "upnp"... Jan 12 10:28:00 volumio kernel: FS-Cache: Loaded Jan 12 10:28:00 volumio kernel: Key type dns_resolver registered Jan 12 10:28:00 volumio volumio[889]: info: [1768184880509] Starting Upmpd Daemon Jan 12 10:28:00 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 10:28:00 volumio volumio[889]: info: Loading plugin "my_music"... Jan 12 10:28:00 volumio volumio[889]: info: Loading plugin "mpd"... Jan 12 10:28:00 volumio systemd[1]: Starting Bluetooth service... Jan 12 10:28:00 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Jan 12 10:28:00 volumio kernel: Key type cifs.spnego registered Jan 12 10:28:00 volumio kernel: Key type cifs.idmap registered Jan 12 10:28:00 volumio kernel: CIFS: Attempting to mount //192.168.50.198/music/CD1-210G Jan 12 10:28:00 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 12 10:28:00 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jan 12 10:28:00 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:00 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jan 12 10:28:00 volumio systemd[1]: Starting Preprocess NFS configuration... Jan 12 10:28:00 volumio systemd[1]: Reached target Host and Network Name Lookups. Jan 12 10:28:00 volumio systemd[1]: nfs-config.service: Succeeded. Jan 12 10:28:00 volumio systemd[1]: Started Preprocess NFS configuration. Jan 12 10:28:00 volumio systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Jan 12 10:28:00 volumio systemd[1]: Starting Notify NFS peers of a restart... Jan 12 10:28:00 volumio sm-notify[1190]: Version 1.3.3 starting Jan 12 10:28:00 volumio systemd[1]: rpc-statd-notify.service: Succeeded. Jan 12 10:28:00 volumio systemd[1]: Started Notify NFS peers of a restart. Jan 12 10:28:00 volumio rpc.statd[1192]: Version 1.3.3 starting Jan 12 10:28:00 volumio rpc.statd[1192]: Flags: TI-RPC Jan 12 10:28:00 volumio systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Jan 12 10:28:00 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:01 volumio volumio[889]: info: Loading plugin "upnp_browser"... Jan 12 10:28:01 volumio bluetoothd[1154]: Bluetooth daemon 5.50 Jan 12 10:28:01 volumio kernel: Bluetooth: Core ver 2.22 Jan 12 10:28:01 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Jan 12 10:28:01 volumio kernel: Bluetooth: HCI device and connection manager initialized Jan 12 10:28:01 volumio kernel: Bluetooth: HCI socket layer initialized Jan 12 10:28:01 volumio kernel: Bluetooth: L2CAP socket layer initialized Jan 12 10:28:01 volumio kernel: Bluetooth: SCO socket layer initialized Jan 12 10:28:01 volumio bluetoothd[1154]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 12 10:28:01 volumio dbus-daemon[665]: [system] Successfully activated service 'org.bluez' Jan 12 10:28:01 volumio systemd[1]: Started Bluetooth service. Jan 12 10:28:01 volumio bluetoothd[1154]: Starting SDP server Jan 12 10:28:01 volumio bluetoothd[1154]: Excluding (cli) sap Jan 12 10:28:01 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 12 10:28:01 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jan 12 10:28:01 volumio kernel: Bluetooth: BNEP socket layer initialized Jan 12 10:28:01 volumio bluetoothd[1154]: Bluetooth management interface 1.22 initialized Jan 12 10:28:01 volumio dbus-daemon[665]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.7' (uid=0 pid=1154 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 12 10:28:01 volumio systemd[1]: Starting Hostname Service... Jan 12 10:28:01 volumio dbus-daemon[665]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 12 10:28:01 volumio systemd[1]: Started Hostname Service. Jan 12 10:28:01 volumio volumio[889]: info: Loading plugin "alarm-clock"... Jan 12 10:28:01 volumio volumio[889]: info: Loading plugin "airplay_emulation"... Jan 12 10:28:01 volumio volumio[889]: info: Starting Shairport Sync Jan 12 10:28:01 volumio volumio[889]: info: Loading plugin "last_100"... Jan 12 10:28:01 volumio volumio[889]: info: Loading plugin "webradio"... Jan 12 10:28:01 volumio volumio[889]: info: Loading plugin "i2s_dacs"... Jan 12 10:28:01 volumio volumio[889]: info: I2S DAC not set, start Auto-detection Jan 12 10:28:01 volumio volumio[889]: info: Loading plugin "volumiodiscovery"... Jan 12 10:28:01 volumio volumio[889]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 10:28:01 volumio volumio[889]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 10:28:01 volumio volumio[889]: *** WARNING *** For more information see Jan 12 10:28:01 volumio volumio[889]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 10:28:01 volumio volumio[889]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 10:28:01 volumio volumio[889]: *** WARNING *** For more information see Jan 12 10:28:01 volumio node[889]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 10:28:01 volumio node[889]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 10:28:01 volumio node[889]: *** WARNING *** For more information see Jan 12 10:28:01 volumio node[889]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 10:28:01 volumio node[889]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 10:28:01 volumio node[889]: *** WARNING *** For more information see Jan 12 10:28:01 volumio volumio[889]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 12 10:28:01 volumio volumio[889]: info: Discovery: Started advertising with name: Volumio Jan 12 10:28:01 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 10:28:01 volumio volumio[889]: info: Plugin jellyfin is not enabled Jan 12 10:28:01 volumio volumio[889]: info: Plugin jellyfin_server is not enabled Jan 12 10:28:01 volumio volumio[889]: info: Plugin autostart is not enabled Jan 12 10:28:01 volumio volumio[889]: info: Loading plugin "peppy_screensaver"... Jan 12 10:28:02 volumio volumio[889]: info: Applying required configuration parameters for plugin peppy_screensaver Jan 12 10:28:02 volumio volumio[889]: info: Loading plugin "outputs"... Jan 12 10:28:02 volumio volumio[889]: info: Loading plugin "albumart"... Jan 12 10:28:02 volumio volumio[889]: info: Plugin example_plugin is not enabled Jan 12 10:28:02 volumio volumio[889]: info: Loading plugin "inputs"... Jan 12 10:28:02 volumio volumio[889]: info: Loading plugin "updater_comm"... Jan 12 10:28:02 volumio volumio[889]: Forking 3 albumart workers Jan 12 10:28:02 volumio volumio[889]: info: Plugin mpdemulation is not enabled Jan 12 10:28:02 volumio volumio[889]: info: Loading plugin "rest_api"... Jan 12 10:28:02 volumio volumio[889]: info: Loading plugin "websocket"... Jan 12 10:28:02 volumio volumio[889]: info: Starting Socket.io Server version 2.3.0 Jan 12 10:28:02 volumio volumio[889]: info: Plugin RoonBridge is not enabled Jan 12 10:28:02 volumio volumio[889]: info: Plugin lms is not enabled Jan 12 10:28:02 volumio volumio[889]: info: Plugin minidlna is not enabled Jan 12 10:28:02 volumio volumio[889]: info: Loading i18n strings for locale zh Jan 12 10:28:02 volumio volumio[889]: Updating browse sources language Jan 12 10:28:02 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 10:28:02 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 10:28:02 volumio volumio[889]: info: CoreCommandRouter::initPlayerControls Jan 12 10:28:02 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:02 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:02 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:02 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 10:28:02 volumio volumio[889]: Express server listening on port 3000 Jan 12 10:28:02 volumio volumio[889]: [Metrics] WebUI: 5s 946.45ms Jan 12 10:28:02 volumio volumio[889]: info: Setting Device type: x86 Jan 12 10:28:02 volumio volumio[889]: info: CoreStateMachine::resetVolumioState Jan 12 10:28:02 volumio volumio[889]: info: CoreStateMachine::getcurrentVolume Jan 12 10:28:02 volumio volumio[889]: info: CoreCommandRouter::volumioRetrievevolume Jan 12 10:28:02 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:02 volumio volumio[889]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 12 10:28:02 volumio volumio[889]: info: Completed loading Core Plugins Jan 12 10:28:02 volumio volumio[889]: info: Preparing to generate the ALSA configuration file Jan 12 10:28:02 volumio volumio[889]: Starting albumart workers Jan 12 10:28:02 volumio volumio[889]: Starting albumart workers Jan 12 10:28:02 volumio volumio[889]: Starting albumart workers Jan 12 10:28:02 volumio volumio[889]: info: Cannot mount NAS 华语流行音乐-375 at system boot, trial number 1 ,retrying in 5 seconds Jan 12 10:28:02 volumio volumio[889]: info: Cannot mount NAS CD1-210G at system boot, trial number 1 ,retrying in 5 seconds Jan 12 10:28:02 volumio volumio[889]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 12 10:28:02 volumio volumio[889]: wlan0 Interface doesn't support scanning. Jan 12 10:28:02 volumio volumio[889]: info: Cannot use regular scanning, forcing with ap-force Jan 12 10:28:02 volumio sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 12 10:28:02 volumio sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:02 volumio sudo[1317]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:02 volumio volumio[889]: command failed: No such device (-19) Jan 12 10:28:02 volumio volumio[889]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 12 10:28:02 volumio volumio[889]: command failed: No such device (-19) Jan 12 10:28:02 volumio volumio[889]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 12 10:28:02 volumio volumio[889]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jan 12 10:28:02 volumio volumio[889]: info: Reading ALSA contributions from plugins. Jan 12 10:28:02 volumio volumio-remote-updater[648]: [2026-01-12 10:28:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1768184879 101 Jan 12 10:28:02 volumio volumio[889]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jan 12 10:28:03 volumio volumio[889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 12 10:28:03 volumio volumio[889]: info: Reloading queue from file Jan 12 10:28:03 volumio volumio[889]: info: VolumeController:: Volume=100 Mute =false Jan 12 10:28:03 volumio volumio[889]: info: CoreStateMachine::pushState Jan 12 10:28:03 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 12 10:28:03 volumio volumio[889]: info: CoreStateMachine::updateTrackBlock Jan 12 10:28:03 volumio volumio[889]: info: CorePlayQueue::getTrackBlock Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::volumioRetrievevolume Jan 12 10:28:03 volumio volumio[889]: info: CoreStateMachine::setRepeat null single undefined Jan 12 10:28:03 volumio volumio[889]: info: CoreStateMachine::pushState Jan 12 10:28:03 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 12 10:28:03 volumio volumio[889]: info: CoreStateMachine::setRandom null Jan 12 10:28:03 volumio volumio[889]: info: CoreStateMachine::pushState Jan 12 10:28:03 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 12 10:28:03 volumio volumio[889]: info: VolumeController:: Volume=100 Mute =false Jan 12 10:28:03 volumio volumio[889]: info: CoreStateMachine::pushState Jan 12 10:28:03 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::volumioPushState Jan 12 10:28:03 volumio volumio[889]: info: Asound.conf file unchanged, so no further update is needed Jan 12 10:28:03 volumio volumio[889]: info: Output device has changed, restarting MPD Jan 12 10:28:03 volumio sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 10:28:03 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:03 volumio volumio[889]: info: Output device has changed, restarting Shairport Sync Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:03 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:03 volumio dhcpcd[768]: eth0: leased 192.168.50.153 for 43200 seconds Jan 12 10:28:03 volumio dhcpcd[768]: eth0: adding route to 192.168.50.0/24 Jan 12 10:28:03 volumio dhcpcd[768]: eth0: adding default route via 192.168.50.1 Jan 12 10:28:03 volumio avahi-daemon[668]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.50.153. Jan 12 10:28:03 volumio avahi-daemon[668]: New relevant interface eth0.IPv4 for mDNS. Jan 12 10:28:03 volumio avahi-daemon[668]: Registering new address record for 192.168.50.153 on eth0.IPv4. Jan 12 10:28:03 volumio sudo[1356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 10:28:03 volumio sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:03 volumio volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 10:28:03 volumio volumio[889]: info: ___________ START PLUGINS ___________ Jan 12 10:28:03 volumio systemd[1]: Listening on mpd.socket. Jan 12 10:28:03 volumio volumio[889]: info: ControllerMpd::onStart: Initializing MPD Jan 12 10:28:03 volumio volumio[889]: info: Creating MPD Configuration file Jan 12 10:28:03 volumio systemd[1]: Starting Music Player Daemon... Jan 12 10:28:03 volumio sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 10:28:03 volumio sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 10:28:03 volumio sudo[1383]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 10:28:03 volumio volumio[889]: info: [1768184883406] CoreMusicLibrary::Adding element 媒体服务器 Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 10:28:03 volumio sudo[1386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 10:28:03 volumio sudo[1386]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:03 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 12 10:28:03 volumio systemd[1]: mpd.service: Succeeded. Jan 12 10:28:03 volumio systemd[1]: Stopped Music Player Daemon. Jan 12 10:28:03 volumio volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:03 volumio systemd[1]: Starting Music Player Daemon... Jan 12 10:28:03 volumio volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 10:28:03 volumio volumio[889]: info: [1768184883512] CoreMusicLibrary::Adding element Last_100 Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 10:28:03 volumio volumio[889]: info: [1768184883514] CoreMusicLibrary::Adding element Webradio Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 10:28:03 volumio volumio[889]: info: Initializing BBC Radios Jan 12 10:28:03 volumio ntpd[853]: ntpd exiting on signal 15 (Terminated) Jan 12 10:28:03 volumio systemd[1]: Stopping Network Time Service... Jan 12 10:28:03 volumio systemd[1]: ntp.service: Succeeded. Jan 12 10:28:03 volumio sudo[1397]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 10:28:03 volumio systemd[1]: Stopped Network Time Service. Jan 12 10:28:03 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:03 volumio sudo[1397]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 12 10:28:03 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:03 volumio systemd[1]: Starting Network Time Service... Jan 12 10:28:03 volumio ntpd[1410]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 12 10:28:03 volumio systemd[1]: Started Network Time Service. Jan 12 10:28:03 volumio ntpd[1410]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jan 12 10:28:03 volumio ntpd[1423]: proto: precision = 0.168 usec (-22) Jan 12 10:28:03 volumio ntpd[1423]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 12 10:28:03 volumio ntpd[1423]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 12 10:28:03 volumio ntpd[1423]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1112 days ago Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:03 volumio ntpd[1423]: Listen and drop on 0 v6wildcard [::]:123 Jan 12 10:28:03 volumio ntpd[1423]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 12 10:28:03 volumio ntpd[1423]: Listen normally on 2 lo 127.0.0.1:123 Jan 12 10:28:03 volumio ntpd[1423]: Listen normally on 3 eth0 192.168.50.153:123 Jan 12 10:28:03 volumio ntpd[1423]: Listening on routing socket on fd #20 for interface updates Jan 12 10:28:03 volumio ntpd[1423]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 12 10:28:03 volumio ntpd[1423]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 12 10:28:03 volumio volumio[889]: info: Loading i18n strings for locale zh Jan 12 10:28:03 volumio volumio[889]: info: /tmp/myfifo created Jan 12 10:28:03 volumio volumio[889]: info: /tmp/myfifosa created Jan 12 10:28:03 volumio sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jan 12 10:28:03 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:03 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:03 volumio volumio[889]: info: snd-dummy loaded Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:03 volumio volumio[889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 10:28:03 volumio volumio[889]: info: Volumio Calling Home Jan 12 10:28:03 volumio volumio[889]: verbose: New Socket.io Connection to 192.168.50.153 from 192.168.50.116 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 3 Jan 12 10:28:03 volumio volumio[889]: verbose: New Socket.io Connection to 192.168.50.153 from 192.168.50.116 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 12 10:28:03 volumio volumio[889]: verbose: New Socket.io Connection to 192.168.50.153 from 192.168.50.116 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 5 Jan 12 10:28:03 volumio volumio[889]: info: MPD Permissions set Jan 12 10:28:03 volumio volumio[889]: info: MPD Permissions set Jan 12 10:28:03 volumio volumio[889]: verbose: New Socket.io Connection to 192.168.50.153 from 192.168.50.116 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 6 Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:04 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:04 volumio volumio[889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jan 12 10:28:04 volumio sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:04 volumio volumio[889]: info: Starting Shairport Sync Jan 12 10:28:04 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:04 volumio volumio[889]: info: Starting Shairport Sync Jan 12 10:28:04 volumio sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 10:28:04 volumio volumio[889]: info: Starting Shairport Sync Jan 12 10:28:04 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 10:28:04 volumio volumio[889]: info: Starting Shairport Sync Jan 12 10:28:04 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 12 10:28:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 10:28:04 volumio sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 10:28:04 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:04 volumio sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:04 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:04 volumio volumio[889]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785 Jan 12 10:28:04 volumio volumio[889]: info: Discovery: Found device Volumio Jan 12 10:28:04 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:04 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:04 volumio sudo[1474]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:04 volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 12 10:28:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 10:28:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 12 10:28:04 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 12 10:28:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 10:28:04 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Jan 12 10:28:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 10:28:04 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:04 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 12 10:28:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 12 10:28:04 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 12 10:28:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 12 10:28:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 12 10:28:04 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:04 volumio sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 10:28:04 volumio sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:04 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:04 volumio volumio[889]: info: Shairport-Sync Started Jan 12 10:28:04 volumio volumio[889]: Error adding Membership: Error: addMembership EINVAL Jan 12 10:28:04 volumio volumio[889]: info: Shairport-Sync Started Jan 12 10:28:04 volumio volumio[889]: info: Volumio called home Jan 12 10:28:04 volumio volumio[889]: info: Shairport-Sync Started Jan 12 10:28:04 volumio volumio[889]: info: MPD Permissions set Jan 12 10:28:04 volumio volumio[889]: info: Shairport-Sync Started Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:04 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:04 volumio screensaver.sh[661]: 1441 Jan 12 10:28:04 volumio ntpd[1423]: Soliciting pool server 101.42.50.95 Jan 12 10:28:04 volumio volumio[889]: warn: peppy_screensaver: An error occurred on pygame check Command failed: python3 -c "import pygame" Jan 12 10:28:04 volumio volumio[889]: Terminated Jan 12 10:28:04 volumio volumio[889]: {"killed":false,"code":143,"signal":null,"cmd":"python3 -c \"import pygame\"","stack":"Error: Command failed: python3 -c \"import pygame\"\nTerminated\n\n at ChildProcess.exithandler (child_process.js:383:12)\n at ChildProcess.emit (events.js:400:28)\n at maybeClose (internal/child_process.js:1088:16)\n at Socket. (internal/child_process.js:446:11)\n at Socket.emit (events.js:400:28)\n at Pipe. (net.js:686:12)"} Jan 12 10:28:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jan 12 10:28:04 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 10:28:04 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 12 10:28:04 volumio systemd[1]: mpd.service: Succeeded. Jan 12 10:28:04 volumio systemd[1]: Stopped Music Player Daemon. Jan 12 10:28:04 volumio systemd[1]: Starting Music Player Daemon... Jan 12 10:28:05 volumio sudo[1510]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 10:28:05 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:05 volumio sudo[1510]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 12 10:28:05 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:05 volumio volumio[889]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 12 10:28:05 volumio nmbd[833]: [2026/01/12 10:28:05.438056, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 12 10:28:05 volumio systemd[1]: Started Samba NMB Daemon. Jan 12 10:28:05 volumio nmbd[833]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 12 10:28:05 volumio systemd[1]: Starting Samba Winbind Daemon... Jan 12 10:28:05 volumio volumio[889]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 10:28:05 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 10:28:05 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:05 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:05 volumio ntpd[1423]: Soliciting pool server 193.182.111.143 Jan 12 10:28:05 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 10:28:05 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 12 10:28:05 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 12 10:28:05 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 10:28:05 volumio winbindd[1514]: [2026/01/12 10:28:05.669797, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 12 10:28:05 volumio winbindd[1514]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 12 10:28:05 volumio winbindd[1514]: [2026/01/12 10:28:05.678989, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 12 10:28:05 volumio winbindd[1514]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 12 10:28:05 volumio systemd[1]: Started Samba Winbind Daemon. Jan 12 10:28:05 volumio systemd[1]: Starting Samba SMB Daemon... Jan 12 10:28:05 volumio mpd[1512]: Jan 12 10:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 10:28:05 volumio systemd[1]: Started Music Player Daemon. Jan 12 10:28:05 volumio sudo[1386]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:05 volumio sudo[1356]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:05 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:05 volumio volumio[889]: info: Completed starting Core Plugins Jan 12 10:28:05 volumio volumio[889]: info: ------------------------------------------- Jan 12 10:28:05 volumio volumio[889]: info: ----- MyVolumio plugins startup ---- Jan 12 10:28:05 volumio volumio[889]: info: ------------------------------------------- Jan 12 10:28:05 volumio volumio[889]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 12 10:28:05 volumio volumio[889]: error: MPD error: The expression evaluated to a falsy value: Jan 12 10:28:05 volumio volumio[889]: assert.ok(self.idling) Jan 12 10:28:05 volumio volumio[889]: error: The expression evaluated to a falsy value: Jan 12 10:28:05 volumio volumio[889]: assert.ok(self.idling) Jan 12 10:28:05 volumio volumio[889]: error: MPD error: The expression evaluated to a falsy value: Jan 12 10:28:05 volumio volumio[889]: assert.ok(self.idling) Jan 12 10:28:05 volumio volumio[889]: error: The expression evaluated to a falsy value: Jan 12 10:28:05 volumio volumio[889]: assert.ok(self.idling) Jan 12 10:28:05 volumio volumio[889]: info: MPD running with PID1512 Jan 12 10:28:05 volumio volumio[889]: ,establishing connection Jan 12 10:28:05 volumio volumio[889]: error: updateQueue error: null Jan 12 10:28:05 volumio volumio[889]: error: updateQueue error: null Jan 12 10:28:06 volumio smbd[1520]: [2026/01/12 10:28:06.029288, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 12 10:28:06 volumio systemd[1]: Started Samba SMB Daemon. Jan 12 10:28:06 volumio smbd[1520]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 12 10:28:06 volumio systemd[1]: Reached target Multi-User System. Jan 12 10:28:06 volumio systemd[1]: Reached target Graphical Interface. Jan 12 10:28:06 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 12 10:28:06 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 12 10:28:06 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 12 10:28:06 volumio systemd[1]: Startup finished in 6.202s (firmware) + 15.225s (loader) + 10.353s (kernel) + 14.104s (userspace) = 45.884s. Jan 12 10:28:06 volumio volumio[889]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 12 10:28:06 volumio volumio[889]: verbose: New Socket.io Connection to 192.168.50.153 from 192.168.50.116 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:06 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 10:28:06 volumio volumio[889]: info: Listing playlists Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::volumioGetQueue Jan 12 10:28:06 volumio volumio[889]: info: CoreStateMachine::getQueue Jan 12 10:28:06 volumio volumio[889]: info: CorePlayQueue::getQueue Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 10:28:06 volumio ntpd[1423]: Soliciting pool server 185.209.85.222 Jan 12 10:28:06 volumio ntpd[1423]: Soliciting pool server 118.25.25.89 Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:06 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 10:28:06 volumio volumio[889]: info: Listing playlists Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 12 10:28:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 10:28:07 volumio ntpd[1423]: Soliciting pool server 111.230.189.174 Jan 12 10:28:07 volumio ntpd[1423]: Soliciting pool server 193.182.111.14 Jan 12 10:28:07 volumio sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,vers=3.11 192.168.50.198:/volume1/music/华语流行音乐-375 /mnt/NAS/华语流行音乐-375 Jan 12 10:28:07 volumio sudo[1711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:07 volumio sudo[1714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=DGjc1234,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.50.198/music/CD1-210G /mnt/NAS/CD1-210G Jan 12 10:28:07 volumio sudo[1714]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:07 volumio kernel: CIFS: Attempting to mount //192.168.50.198/music/CD1-210G Jan 12 10:28:07 volumio sudo[1714]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:08 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 10:28:08 volumio volumio[889]: info: Received Get System Info Jan 12 10:28:08 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 10:28:08 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 10:28:08 volumio volumio[889]: info: Discovery: Getting this device information Jan 12 10:28:08 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:08 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:08 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 10:28:08 volumio sudo[1711]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:08 volumio ntpd[1423]: Soliciting pool server 139.199.214.202 Jan 12 10:28:09 volumio ntpd[1423]: Soliciting pool server 84.16.67.12 Jan 12 10:28:10 volumio sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 10:28:10 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:10 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:10 volumio sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 10:28:10 volumio sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:10 volumio sudo[1741]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:10 volumio sudo[1745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 12 10:28:10 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:10 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 12 10:28:10 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:10 volumio volumio[889]: info: Upmpdcli Daemon Started Jan 12 10:28:10 volumio volumio[1748]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 12 10:28:11 volumio volumio[889]: Cannot compose Albumart path Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 12 10:28:15 volumio volumio[889]: info: Adding plugin bluetooth to MyMusic Plugins Jan 12 10:28:15 volumio volumio[889]: info: Adding plugin multiroom to MyMusic Plugins Jan 12 10:28:15 volumio volumio[889]: info: Adding plugin metavolumio to MyMusic Plugins Jan 12 10:28:15 volumio volumio[889]: info: Adding plugin cd_controller to MyMusic Plugins Jan 12 10:28:15 volumio volumio[889]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 12 10:28:15 volumio volumio[889]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 12 10:28:15 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:15 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:15 volumio volumio[889]: info: Starting MyVolumio Remote Streaming Endpoints Jan 12 10:28:15 volumio volumio[889]: info: MyVolumio login type: Token Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 12 10:28:15 volumio volumio[889]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 12 10:28:15 volumio volumio[1748]: ..............................................................................................................................................................................................................................................++++ Jan 12 10:28:16 volumio volumio[889]: info: Starting Streaming Service Transparent Proxy Jan 12 10:28:16 volumio volumio[889]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 12 10:28:16 volumio volumio[889]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 12 10:28:16 volumio volumio[889]: info: Streaming services startup Jan 12 10:28:16 volumio volumio[889]: info: Starting Streaming Daemon Jan 12 10:28:16 volumio sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 10:28:16 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 10:28:16 volumio sudo[1775]: pam_unix(sudo:session): session closed for user root Jan 12 10:28:16 volumio volumio[889]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 12 10:28:16 volumio volumio[889]: error: Cannot start Volumio Streaming Daemon Jan 12 10:28:16 volumio volumio[889]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 10:28:16 volumio volumio[889]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 10:28:16 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:16 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:17 volumio volumio[889]: STREAMING PROXY: Starting server on port 3245 Jan 12 10:28:17 volumio volumio[889]: Node JS runtime: 14 Jan 12 10:28:20 volumio volumio[1748]: ............................................................................................................................................................................................................++++ Jan 12 10:28:20 volumio volumio[1748]: e is 65537 (0x010001) Jan 12 10:28:20 volumio volumio[1748]: writing RSA key Jan 12 10:28:24 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 12 10:28:28 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:28 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:30 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 10:28:30 volumio volumio[889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 12 10:28:30 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:30 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:31 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jan 12 10:28:34 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 10:28:34 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:34 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 10:28:35 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:35 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:35 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 12 10:28:35 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 12 10:28:35 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 12 10:28:35 volumio volumio[889]: info: CoreCommandRouter::volumioGetBrowseSources Jan 12 10:28:35 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 10:28:36 volumio volumio[889]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 12 10:28:36 volumio volumio[889]: info: Completed starting MyVolumio Plugin Jan 12 10:28:36 volumio volumio[889]: info: BOOT COMPLETED Jan 12 10:28:36 volumio volumio[889]: [Metrics] CommandRouter: 38s 923.74ms Jan 12 10:28:36 volumio volumio[889]: info: CoreCommandRouter::volumiosetStartupVolume Jan 12 10:28:36 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 10:28:36 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 10:28:36 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 12 10:28:36 volumio volumio[889]: info: CoreCommandRouter::Close All Modals sent Jan 12 10:28:37 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 12 10:28:37 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 12 10:28:37 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 12 10:28:39 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:39 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:42 volumio sshd[1819]: Accepted password for volumio from 192.168.50.116 port 55159 ssh2 Jan 12 10:28:42 volumio sshd[1819]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jan 12 10:28:42 volumio systemd[1]: Created slice User Slice of UID 1000. Jan 12 10:28:42 volumio systemd[1]: Starting User Runtime Directory /run/user/1000... Jan 12 10:28:42 volumio systemd-logind[655]: New session 1 of user volumio. Jan 12 10:28:42 volumio systemd[1]: Started User Runtime Directory /run/user/1000. Jan 12 10:28:42 volumio systemd[1]: Starting User Manager for UID 1000... Jan 12 10:28:42 volumio systemd[1831]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jan 12 10:28:42 volumio systemd[1831]: Reached target Paths. Jan 12 10:28:42 volumio systemd[1831]: Starting D-Bus User Message Bus Socket. Jan 12 10:28:42 volumio systemd[1831]: Listening on GnuPG cryptographic agent and passphrase cache. Jan 12 10:28:42 volumio systemd[1831]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Jan 12 10:28:42 volumio systemd[1831]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Jan 12 10:28:42 volumio systemd[1831]: Listening on Sound System. Jan 12 10:28:42 volumio systemd[1831]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Jan 12 10:28:42 volumio systemd[1831]: Reached target Timers. Jan 12 10:28:42 volumio systemd[1831]: Listening on GnuPG network certificate management daemon. Jan 12 10:28:42 volumio systemd[1831]: Listening on D-Bus User Message Bus Socket. Jan 12 10:28:42 volumio systemd[1831]: Reached target Sockets. Jan 12 10:28:42 volumio systemd[1831]: Reached target Basic System. Jan 12 10:28:42 volumio systemd[1]: Started User Manager for UID 1000. Jan 12 10:28:42 volumio systemd[1831]: Starting Music Player Daemon... Jan 12 10:28:42 volumio systemd[1]: Started Session 1 of user volumio. Jan 12 10:28:43 volumio mpd[1842]: exception: failed to open log file "/var/log/mpd.log" (config line 8): Permission denied Jan 12 10:28:43 volumio systemd[1831]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 12 10:28:43 volumio systemd[1831]: mpd.service: Failed with result 'exit-code'. Jan 12 10:28:43 volumio systemd[1831]: Failed to start Music Player Daemon. Jan 12 10:28:43 volumio systemd[1831]: Reached target Default. Jan 12 10:28:43 volumio systemd[1831]: Startup finished in 842ms. Jan 12 10:28:46 volumio volumio[889]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 12 10:28:46 volumio volumio[889]: info: Retrying Login Due to Network Error Jan 12 10:28:50 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:28:50 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:28:56 volumio volumio[889]: info: MyVolumio login type: Token Jan 12 10:29:01 volumio volumio[889]: info: CoreCommandRouter::volumioGetState Jan 12 10:29:01 volumio volumio[889]: info: CorePlayQueue::getTrack 0 Jan 12 10:29:08 volumio volumio[889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 10:29:08 volumio volumio[889]: Error: connect ECONNREFUSED 23.101.24.70:80 Jan 12 10:29:08 volumio volumio[889]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 12 10:29:08 volumio volumio[889]: errno: -111, Jan 12 10:29:08 volumio volumio[889]: code: 'ECONNREFUSED', Jan 12 10:29:08 volumio volumio[889]: syscall: 'connect', Jan 12 10:29:08 volumio volumio[889]: address: '23.101.24.70', Jan 12 10:29:08 volumio volumio[889]: port: 80 Jan 12 10:29:08 volumio volumio[889]: } Jan 12 10:29:08 volumio volumio[889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 10:29:08 volumio sudo[1921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 10:28 Jan 12 10:29:08 volumio sudo[1921]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"