-- Logs begin at Sun 2025-06-08 10:28:01 CST, end at Wed 2025-06-18 07:48:13 CST. -- Jun 18 07:47:58 volumio systemd-timedated[1028]: Changed local time to Wed Jun 18 07:47:58 2025 Jun 18 07:47:58 volumio sudo[1026]: pam_unix(sudo:session): session closed for user root Jun 18 07:47:58 volumio volumio-time-update[594]: volumio-time-update-util: System time updated successfully. Jun 18 07:47:58 volumio systemd[1]: Starting Daily apt download activities... Jun 18 07:47:58 volumio systemd[1]: Started Volumio Time Update Utility. Jun 18 07:47:58 volumio systemd[1]: Reached target Multi-User System. Jun 18 07:47:58 volumio systemd[1]: Reached target Graphical Interface. Jun 18 07:47:58 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 18 07:47:58 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 18 07:47:58 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 18 07:47:58 volumio nmbd[774]: [2025/06/18 07:47:58.487729, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 18 07:47:58 volumio nmbd[774]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.14 for name WORKGROUP<1d>. Jun 18 07:47:58 volumio nmbd[774]: This response was from IP 192.168.2.123, reporting an IP address of 192.168.2.123. Jun 18 07:47:58 volumio nmbd[774]: [2025/06/18 07:47:58.488082, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 18 07:47:58 volumio nmbd[774]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.2.14 for name WORKGROUP<1d>. Jun 18 07:47:58 volumio nmbd[774]: This response was from IP 192.168.2.100, reporting an IP address of 192.168.2.100. Jun 18 07:47:58 volumio nmbd[774]: [2025/06/18 07:47:58.488343, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 18 07:47:58 volumio nmbd[774]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.2.14 for name WORKGROUP<1d>. Jun 18 07:47:58 volumio nmbd[774]: This response was from IP 192.168.2.100, reporting an IP address of 192.168.2.100. Jun 18 07:47:59 volumio systemd[1]: apt-daily.service: Succeeded. Jun 18 07:47:59 volumio systemd[1]: Started Daily apt download activities. Jun 18 07:47:59 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jun 18 07:47:59 volumio volumio[978]: info: Loading plugin "network"... Jun 18 07:47:59 volumio volumio[978]: info: Refreshing Cached IP Addresses Jun 18 07:47:59 volumio sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 07:47:59 volumio sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:47:59 volumio sudo[1088]: pam_unix(sudo:session): session closed for user root Jun 18 07:47:59 volumio sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 07:47:59 volumio sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:47:59 volumio sudo[1092]: pam_unix(sudo:session): session closed for user root Jun 18 07:47:59 volumio volumio[978]: info: Loading plugin "services"... Jun 18 07:47:59 volumio volumio[978]: info: Loading plugin "alsa_controller"... Jun 18 07:47:59 volumio sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 18 07:47:59 volumio sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:47:59 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 07:47:59 volumio volumio[978]: info: Loading plugin "wizard"... Jun 18 07:47:59 volumio volumio[978]: info: Loading plugin "networkfs"... Jun 18 07:47:59 volumio volumio[978]: info: Starting Udev Watcher for removable devices Jun 18 07:47:59 volumio sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=lian9412,password=cy206888,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.100/music /mnt/NAS/NAS1 Jun 18 07:47:59 volumio sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:47:59 volumio volumio[978]: info: Ignoring mount for partition: boot Jun 18 07:47:59 volumio volumio[978]: info: Ignoring mount for partition: volumio Jun 18 07:47:59 volumio volumio[978]: info: Ignoring mount for partition: volumio_data Jun 18 07:47:59 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 07:47:59 volumio volumio[978]: info: Loading plugin "volumio_command_line_client"... Jun 18 07:47:59 volumio volumio[978]: info: Loading plugin "upnp"... Jun 18 07:47:59 volumio volumio[978]: info: [1750204079787] Starting Upmpd Daemon Jun 18 07:47:59 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 07:47:59 volumio volumio[978]: info: Loading plugin "my_music"... Jun 18 07:47:59 volumio volumio[978]: info: Loading plugin "mpd"... Jun 18 07:47:59 volumio kernel: Key type cifs.spnego registered Jun 18 07:47:59 volumio kernel: Key type cifs.idmap registered Jun 18 07:47:59 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jun 18 07:47:59 volumio kernel: CIFS: Attempting to mount //192.168.2.100/music Jun 18 07:48:00 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jun 18 07:48:00 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 18 07:48:00 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jun 18 07:48:00 volumio systemd[1]: Startup finished in 13.439s (kernel) + 24.507s (userspace) = 37.947s. Jun 18 07:48:00 volumio volumio[978]: info: Loading plugin "upnp_browser"... Jun 18 07:48:00 volumio kernel: CIFS: VFS: BAD_NETWORK_NAME: \\192.168.2.100\music Jun 18 07:48:00 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Jun 18 07:48:00 volumio sudo[1131]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:00 volumio sudo[1109]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:00 volumio volumio[978]: info: Loading plugin "alarm-clock"... Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "airplay_emulation"... Jun 18 07:48:01 volumio volumio[978]: info: Starting Shairport Sync Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "last_100"... Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "webradio"... Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "i2s_dacs"... Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "volumiodiscovery"... Jun 18 07:48:01 volumio volumio[978]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 07:48:01 volumio volumio[978]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 07:48:01 volumio volumio[978]: *** WARNING *** For more information see Jun 18 07:48:01 volumio volumio[978]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 07:48:01 volumio node[978]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 07:48:01 volumio volumio[978]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 07:48:01 volumio volumio[978]: *** WARNING *** For more information see Jun 18 07:48:01 volumio node[978]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 07:48:01 volumio node[978]: *** WARNING *** For more information see Jun 18 07:48:01 volumio node[978]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 07:48:01 volumio node[978]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 07:48:01 volumio node[978]: *** WARNING *** For more information see Jun 18 07:48:01 volumio volumio[978]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 07:48:01 volumio volumio[978]: info: Discovery: Started advertising with name: Volumio Jun 18 07:48:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "outputs"... Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "albumart"... Jun 18 07:48:01 volumio volumio[978]: info: Plugin example_plugin is not enabled Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "inputs"... Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "updater_comm"... Jun 18 07:48:01 volumio volumio[978]: info: Plugin mpdemulation is not enabled Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "rest_api"... Jun 18 07:48:01 volumio volumio[978]: info: Loading plugin "websocket"... Jun 18 07:48:01 volumio volumio[978]: info: Starting Socket.io Server version 2.3.0 Jun 18 07:48:01 volumio volumio[978]: Forking 3 albumart workers Jun 18 07:48:01 volumio volumio[978]: info: Loading i18n strings for locale zh Jun 18 07:48:01 volumio volumio[978]: Updating browse sources language Jun 18 07:48:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::initPlayerControls Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 07:48:02 volumio volumio[978]: Express server listening on port 3000 Jun 18 07:48:02 volumio volumio[978]: [Metrics] WebUI: 7s 223.66ms Jun 18 07:48:02 volumio volumio[978]: info: CoreStateMachine::resetVolumioState Jun 18 07:48:02 volumio volumio[978]: info: CoreStateMachine::getcurrentVolume Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 07:48:02 volumio volumio[978]: info: Volumio Network Manager: Network status updated: 2 Jun 18 07:48:02 volumio volumio[978]: info: Cannot mount NAS NAS1 at system boot, trial number 1 ,retrying in 5 seconds Jun 18 07:48:02 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.2.14 from 192.168.2.44 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 1 Jun 18 07:48:02 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.2.14 from 192.168.2.44 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 2 Jun 18 07:48:02 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.2.14 from 192.168.2.44 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 3 Jun 18 07:48:02 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.2.14 from 192.168.2.44 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 4 Jun 18 07:48:02 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.2.14 from 192.168.2.44 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Jun 18 07:48:02 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.2.14 from 192.168.2.44 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 Jun 18 07:48:02 volumio volumio[978]: info: CoreStateMachine::pushState Jun 18 07:48:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Jun 18 07:48:02 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Jun 18 07:48:02 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Jun 18 07:48:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Jun 18 07:48:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 07:48:02 volumio volumio[978]: info: Reloading queue from file Jun 18 07:48:02 volumio volumio[978]: info: CoreStateMachine::pushState Jun 18 07:48:02 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:02 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Jun 18 07:48:03 volumio volumio[978]: info: CoreStateMachine::setRepeat true single undefined Jun 18 07:48:03 volumio volumio[978]: info: CoreStateMachine::pushState Jun 18 07:48:03 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Jun 18 07:48:03 volumio volumio[978]: info: CoreStateMachine::setRandom false Jun 18 07:48:03 volumio volumio[978]: info: CoreStateMachine::pushState Jun 18 07:48:03 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 07:48:03 volumio volumio[978]: Starting albumart workers Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Jun 18 07:48:03 volumio volumio[978]: info: Setting Device type: Raspberry PI Jun 18 07:48:03 volumio volumio[978]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 18 07:48:03 volumio volumio[978]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jun 18 07:48:03 volumio volumio[978]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 18 07:48:03 volumio volumio[978]: Starting albumart workers Jun 18 07:48:03 volumio volumio[978]: info: Listing playlists Jun 18 07:48:03 volumio volumio[978]: info: Completed loading Core Plugins Jun 18 07:48:03 volumio volumio[978]: info: Preparing to generate the ALSA configuration file Jun 18 07:48:03 volumio volumio[978]: Starting albumart workers Jun 18 07:48:03 volumio volumio[978]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 07:48:03 volumio volumio[978]: info: Reading ALSA contributions from plugins. Jun 18 07:48:03 volumio volumio[978]: info: Asound.conf file unchanged, so no further update is needed Jun 18 07:48:03 volumio volumio[978]: info: Output device has changed, restarting MPD Jun 18 07:48:03 volumio volumio[978]: info: Output device has changed, restarting Shairport Sync Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 07:48:03 volumio sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 07:48:03 volumio sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 07:48:03 volumio sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:03 volumio sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:03 volumio sudo[1245]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:03 volumio systemd[1]: Stopping Music Player Daemon... Jun 18 07:48:03 volumio volumio[978]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 07:48:03 volumio volumio[978]: info: ___________ START PLUGINS ___________ Jun 18 07:48:03 volumio volumio[978]: info: ControllerMpd::onStart: Initializing MPD Jun 18 07:48:03 volumio volumio[978]: info: Creating MPD Configuration file Jun 18 07:48:03 volumio sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 07:48:03 volumio sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:03 volumio sudo[1254]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 07:48:03 volumio volumio[978]: info: [1750204083363] CoreMusicLibrary::Adding element 媒体服务器 Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 07:48:03 volumio systemd[1]: mpd.service: Succeeded. Jun 18 07:48:03 volumio sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 18 07:48:03 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 07:48:03 volumio sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:03 volumio systemd[1]: Starting Music Player Daemon... Jun 18 07:48:03 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 07:48:03 volumio systemd[1]: mpd.service: Succeeded. Jun 18 07:48:03 volumio systemd[1]: Stopped Music Player Daemon. Jun 18 07:48:03 volumio systemd[1]: Starting Music Player Daemon... Jun 18 07:48:03 volumio volumio[978]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 07:48:03 volumio volumio[978]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 07:48:03 volumio volumio[978]: info: [1750204083621] CoreMusicLibrary::Adding element Last_100 Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 07:48:03 volumio volumio[978]: info: [1750204083626] CoreMusicLibrary::Adding element Webradio Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 07:48:03 volumio volumio[978]: info: Initializing BBC Radios Jun 18 07:48:03 volumio sudo[1263]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 07:48:03 volumio sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:03 volumio sudo[1263]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 07:48:03 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 07:48:03 volumio volumio[978]: info: Volumio Calling Home Jun 18 07:48:03 volumio volumio-remote-updater[612]: [2025-06-18 07:48:03] [connect] Successful connection Jun 18 07:48:04 volumio volumio[978]: info: MPD Permissions set Jun 18 07:48:04 volumio volumio[978]: info: MPD Permissions set Jun 18 07:48:04 volumio volumio-remote-updater[612]: [2025-06-18 07:48:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750204083 101 Jun 18 07:48:04 volumio volumio[978]: 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: 7 Jun 18 07:48:04 volumio volumio[978]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 07:48:04 volumio volumio[978]: info: Starting Shairport Sync Jun 18 07:48:04 volumio volumio[978]: info: Starting Shairport Sync Jun 18 07:48:04 volumio volumio[978]: info: Starting Shairport Sync Jun 18 07:48:04 volumio sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 07:48:04 volumio sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:04 volumio sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 07:48:04 volumio sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:04 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Jun 18 07:48:04 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:04 volumio sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 18 07:48:04 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 18 07:48:04 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 18 07:48:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 18 07:48:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 18 07:48:04 volumio sudo[1315]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:04 volumio volumio[978]: info: Shairport-Sync Started Jun 18 07:48:04 volumio sudo[1317]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:04 volumio volumio[978]: Error adding Membership: Error: addMembership EINVAL Jun 18 07:48:04 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:04 volumio volumio[978]: info: Shairport-Sync Started Jun 18 07:48:04 volumio volumio[978]: info: Shairport-Sync Started Jun 18 07:48:04 volumio volumio[978]: info: Volumio called home Jun 18 07:48:05 volumio mpd[1275]: Jun 18 07:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 07:48:05 volumio systemd[1]: Started Music Player Daemon. Jun 18 07:48:05 volumio sudo[1257]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:05 volumio sudo[1247]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:05 volumio volumio[978]: info: Completed starting Core Plugins Jun 18 07:48:05 volumio volumio[978]: info: ------------------------------------------- Jun 18 07:48:05 volumio volumio[978]: info: ----- MyVolumio plugins startup ---- Jun 18 07:48:05 volumio volumio[978]: info: ------------------------------------------- Jun 18 07:48:05 volumio volumio[978]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 18 07:48:05 volumio volumio[978]: error: MPD error: The expression evaluated to a falsy value: Jun 18 07:48:05 volumio volumio[978]: assert.ok(self.idling) Jun 18 07:48:05 volumio volumio[978]: error: The expression evaluated to a falsy value: Jun 18 07:48:05 volumio volumio[978]: assert.ok(self.idling) Jun 18 07:48:05 volumio volumio[978]: info: MPD running with PID1275 Jun 18 07:48:05 volumio volumio[978]: ,establishing connection Jun 18 07:48:05 volumio volumio[978]: error: MPD error: The expression evaluated to a falsy value: Jun 18 07:48:05 volumio volumio[978]: assert.ok(self.idling) Jun 18 07:48:05 volumio volumio[978]: error: The expression evaluated to a falsy value: Jun 18 07:48:05 volumio volumio[978]: assert.ok(self.idling) Jun 18 07:48:05 volumio volumio[978]: error: updateQueue error: null Jun 18 07:48:06 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Jun 18 07:48:06 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:07 volumio sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 07:48:07 volumio sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:07 volumio sudo[1335]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:07 volumio sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 07:48:07 volumio sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:07 volumio sudo[1337]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:07 volumio volumio[978]: verbose: New Socket.io Connection to 192.168.2.14 from 192.168.2.44 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 9 Jun 18 07:48:07 volumio volumio[978]: info: Discovery: adding 7a85af14-1b0e-4bd1-9a52-689521765ecb Jun 18 07:48:07 volumio volumio[978]: info: Discovery: Found device Volumio Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Jun 18 07:48:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetVisibleSources Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Jun 18 07:48:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 18 07:48:07 volumio volumio[978]: info: Received Get System Info Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 07:48:07 volumio volumio[978]: info: Discovery: Getting this device information Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Jun 18 07:48:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Jun 18 07:48:07 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:07 volumio volumio[978]: info: Listing playlists Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 18 07:48:07 volumio sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=lian9412,password=cy206888,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.100/music /mnt/NAS/NAS1 Jun 18 07:48:07 volumio sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 18 07:48:07 volumio kernel: CIFS: Attempting to mount //192.168.2.100/music Jun 18 07:48:07 volumio kernel: CIFS: VFS: BAD_NETWORK_NAME: \\192.168.2.100\music Jun 18 07:48:07 volumio sudo[1341]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:07 volumio volumio[978]: info: Cannot mount NAS NAS1 at system boot, trial number 2 ,retrying in 5 seconds Jun 18 07:48:07 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Jun 18 07:48:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 07:48:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 07:48:08 volumio volumio[978]: info: Discovery: Getting this device information Jun 18 07:48:08 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Jun 18 07:48:08 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:08 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 07:48:09 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 18 07:48:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 18 07:48:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 18 07:48:09 volumio volumio[978]: info: Received Get System Info Jun 18 07:48:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 07:48:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 07:48:09 volumio volumio[978]: info: Discovery: Getting this device information Jun 18 07:48:09 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Jun 18 07:48:09 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Jun 18 07:48:09 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 07:48:09 volumio sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 18 07:48:09 volumio sudo[1361]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:09 volumio sudo[1361]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:09 volumio sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 18 07:48:09 volumio sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:09 volumio sudo[1363]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:09 volumio sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 18 07:48:09 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:09 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 18 07:48:09 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:09 volumio volumio[978]: info: Upmpdcli Daemon Started Jun 18 07:48:10 volumio volumio[1369]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 18 07:48:10 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Jun 18 07:48:10 volumio volumio[978]: info: CoreStateMachine::getQueue Jun 18 07:48:10 volumio volumio[978]: info: CorePlayQueue::getQueue Jun 18 07:48:10 volumio volumio[978]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 18 07:48:10 volumio sh[523]: timed out Jun 18 07:48:10 volumio dhcpcd[572]: timed out Jun 18 07:48:11 volumio sh[523]: dhcpcd exited Jun 18 07:48:11 volumio dhcpcd[572]: dhcpcd exited Jun 18 07:48:11 volumio sh[523]: ifup: failed to bring up eth0 Jun 18 07:48:11 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 18 07:48:11 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 18 07:48:12 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 18 07:48:12 volumio sudo[1389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=lian9412,password=cy206888,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.100/music /mnt/NAS/NAS1 Jun 18 07:48:12 volumio sudo[1389]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 07:48:12 volumio kernel: CIFS: Attempting to mount //192.168.2.100/music Jun 18 07:48:13 volumio kernel: CIFS: VFS: BAD_NETWORK_NAME: \\192.168.2.100\music Jun 18 07:48:13 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Jun 18 07:48:13 volumio sudo[1389]: pam_unix(sudo:session): session closed for user root Jun 18 07:48:13 volumio volumio[978]: info: Cannot mount NAS NAS1 at system boot, trial number 3 ,retrying in 5 seconds Jun 18 07:48:13 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 18 07:48:13 volumio volumio[978]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 07:48:13 volumio volumio[978]: TypeError: Cannot read property 'length' of undefined Jun 18 07:48:13 volumio volumio[978]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 18 07:48:13 volumio volumio[978]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 18 07:48:13 volumio volumio[978]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 18 07:48:13 volumio volumio[978]: at Parser.emit (events.js:315:20) Jun 18 07:48:13 volumio volumio[978]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 18 07:48:13 volumio volumio[978]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 18 07:48:13 volumio volumio[978]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 18 07:48:13 volumio volumio[978]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 18 07:48:13 volumio volumio[978]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 18 07:48:13 volumio volumio[978]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 18 07:48:13 volumio volumio[978]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 18 07:48:13 volumio volumio[978]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 18 07:48:13 volumio volumio[978]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 18 07:48:13 volumio volumio[978]: at IncomingMessage.emit (events.js:327:22) Jun 18 07:48:13 volumio volumio[978]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 18 07:48:13 volumio volumio[978]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 18 07:48:13 volumio volumio[978]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 07:48:13 volumio volumio[1369]: ........................++++ Jun 18 07:48:13 volumio sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 07:47 Jun 18 07:48:13 volumio sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"