-- Logs begin at Sun 2025-12-21 14:45:32 PST, end at Sun 2025-12-21 14:47:16 PST. -- Dec 21 14:46:00 volumiopi volumio-remote-updater[568]: [2025-12-21 14:46:00] [info] asio async_connect error: system:111 (Connection refused) Dec 21 14:46:00 volumiopi volumio-remote-updater[568]: [2025-12-21 14:46:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 21 14:46:00 volumiopi volumio-remote-updater[568]: [2025-12-21 14:46:00] [error] handle_connect error: Underlying Transport Error Dec 21 14:46:00 volumiopi ntpd[755]: Listen normally on 3 wlan0 192.168.1.9:123 Dec 21 14:46:00 volumiopi ntpd[755]: new interface(s) found: waking up resolver Dec 21 14:46:00 volumiopi kernel: hwmon hwmon1: Undervoltage detected! Dec 21 14:46:01 volumiopi nmbd[697]: [2025/12/21 14:46:01.327764, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 21 14:46:01 volumiopi systemd[1]: Started Samba NMB Daemon. Dec 21 14:46:01 volumiopi nmbd[697]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 21 14:46:01 volumiopi nmbd[697]: [2025/12/21 14:46:01.364845, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 21 14:46:01 volumiopi nmbd[697]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.9 for name WORKGROUP<1d>. Dec 21 14:46:01 volumiopi nmbd[697]: This response was from IP 192.168.1.25, reporting an IP address of 192.168.1.25. Dec 21 14:46:01 volumiopi systemd[1]: Starting Samba Winbind Daemon... Dec 21 14:46:02 volumiopi winbindd[978]: [2025/12/21 14:46:02.066877, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 21 14:46:02 volumiopi winbindd[978]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 21 14:46:02 volumiopi winbindd[978]: [2025/12/21 14:46:02.108834, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 21 14:46:02 volumiopi systemd[1]: Started Samba Winbind Daemon. Dec 21 14:46:02 volumiopi winbindd[978]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 21 14:46:02 volumiopi systemd[1]: Starting Samba SMB Daemon... Dec 21 14:46:02 volumiopi smbd[983]: [2025/12/21 14:46:02.965773, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 21 14:46:02 volumiopi systemd[1]: Started Samba SMB Daemon. Dec 21 14:46:02 volumiopi smbd[983]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 21 14:46:03 volumiopi volumio-time-update[563]: volumio-time-update-util: Fetching time from Volumio... Dec 21 14:46:04 volumiopi volumio-time-update[563]: volumio-time-update-util: Setting system time to: 2025-12-21 14:46:28 Dec 21 14:46:04 volumiopi sudo[996]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-21 14:46:28 Dec 21 14:46:04 volumiopi sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:04 volumiopi dbus-daemon[584]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=997 comm="timedatectl set-time 2025-12-21 14:46:28 ") Dec 21 14:46:04 volumiopi systemd[1]: Starting Time & Date Service... Dec 21 14:46:04 volumiopi systemd[1]: systemd-fsckd.service: Succeeded. Dec 21 14:46:04 volumiopi dbus-daemon[584]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 21 14:46:04 volumiopi systemd[1]: Started Time & Date Service. Dec 21 14:46:28 volumiopi systemd-timedated[1006]: Changed local time to Sun Dec 21 14:46:28 2025 Dec 21 14:46:28 volumiopi sudo[996]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:28 volumiopi volumio-time-update[563]: volumio-time-update-util: System time updated successfully. Dec 21 14:46:28 volumiopi systemd[1]: Started Volumio Time Update Utility. Dec 21 14:46:28 volumiopi systemd[1]: Reached target Multi-User System. Dec 21 14:46:28 volumiopi systemd[1]: Reached target Graphical Interface. Dec 21 14:46:28 volumiopi volumio[961]: info: ------------------------------------------- Dec 21 14:46:28 volumiopi volumio[961]: info: ----- Volumio3 ---- Dec 21 14:46:28 volumiopi volumio[961]: info: ------------------------------------------- Dec 21 14:46:28 volumiopi volumio[961]: info: ----- System startup ---- Dec 21 14:46:28 volumiopi volumio[961]: info: ------------------------------------------- Dec 21 14:46:28 volumiopi systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 21 14:46:28 volumiopi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 21 14:46:28 volumiopi systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 21 14:46:28 volumiopi systemd[1]: Startup finished in 14.211s (kernel) + 34.584s (userspace) = 48.795s. Dec 21 14:46:30 volumiopi volumio[961]: info: MYVOLUMIO Environment detected Dec 21 14:46:30 volumiopi volumio[961]: info: Plugin folders cleanup Dec 21 14:46:30 volumiopi volumio[961]: info: Scanning into folder /volumio/app/plugins/ Dec 21 14:46:30 volumiopi volumio[961]: info: Scanning category audio_interface Dec 21 14:46:30 volumiopi volumio[961]: info: Scanning category miscellanea Dec 21 14:46:30 volumiopi volumio[961]: info: Scanning category music_service Dec 21 14:46:30 volumiopi volumio[961]: info: Scanning category plugins.json Dec 21 14:46:30 volumiopi volumio[961]: info: Scanning category system_controller Dec 21 14:46:30 volumiopi volumio[961]: info: Scanning category user_interface Dec 21 14:46:30 volumiopi volumio[961]: info: Scanning into folder /data/plugins/ Dec 21 14:46:30 volumiopi volumio[961]: info: Scanning category music_service Dec 21 14:46:30 volumiopi volumio[961]: info: Plugin folders cleanup completed Dec 21 14:46:30 volumiopi volumio[961]: info: ------------------------------------------- Dec 21 14:46:30 volumiopi volumio[961]: info: ----- Core plugins startup ---- Dec 21 14:46:30 volumiopi volumio[961]: info: ------------------------------------------- Dec 21 14:46:30 volumiopi volumio[961]: info: Loading plugins from folder /volumio/app/plugins/ Dec 21 14:46:30 volumiopi volumio[961]: info: Adding plugin upnp to MyMusic Plugins Dec 21 14:46:30 volumiopi volumio[961]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 21 14:46:30 volumiopi volumio[961]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 21 14:46:30 volumiopi volumio[961]: info: Loading plugins from folder /data/plugins/ Dec 21 14:46:30 volumiopi volumio[961]: info: Loading plugin "system"... Dec 21 14:46:30 volumiopi volumio[961]: info: Loading plugin "appearance"... Dec 21 14:46:31 volumiopi sh[477]: timed out Dec 21 14:46:31 volumiopi dhcpcd[523]: timed out Dec 21 14:46:31 volumiopi sh[477]: dhcpcd exited Dec 21 14:46:31 volumiopi dhcpcd[523]: dhcpcd exited Dec 21 14:46:31 volumiopi sh[477]: ifup: failed to bring up eth0 Dec 21 14:46:31 volumiopi systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 21 14:46:31 volumiopi systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 21 14:46:33 volumiopi volumio[961]: info: Loading plugin "network"... Dec 21 14:46:33 volumiopi volumio[961]: info: Refreshing Cached IP Addresses Dec 21 14:46:33 volumiopi sudo[1015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 14:46:33 volumiopi sudo[1015]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:33 volumiopi sudo[1017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 14:46:33 volumiopi sudo[1017]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:33 volumiopi sudo[1015]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:33 volumiopi sudo[1017]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:33 volumiopi volumio[961]: info: Loading plugin "services"... Dec 21 14:46:33 volumiopi volumio[961]: info: Loading plugin "alsa_controller"... Dec 21 14:46:33 volumiopi sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 21 14:46:33 volumiopi sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:33 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 14:46:33 volumiopi volumio[961]: info: Loading plugin "wizard"... Dec 21 14:46:33 volumiopi volumio[961]: info: Loading plugin "networkfs"... Dec 21 14:46:33 volumiopi sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=leon_wang21,password=Eaxd983a86,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.57/Music /mnt/NAS/NAS496E9E Dec 21 14:46:33 volumiopi sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:33 volumiopi sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=asus,password=18311,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.8/D /mnt/NAS/ASUS Dec 21 14:46:33 volumiopi sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:33 volumiopi volumio[961]: info: Starting Udev Watcher for removable devices Dec 21 14:46:33 volumiopi sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=leon,password=Eaxd983a86,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.1.25/Music /mnt/NAS/FNOS Dec 21 14:46:33 volumiopi sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:33 volumiopi sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=asus,password=18311,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.8/\\E\\MUSIC /mnt/NAS/ASUS Dec 21 14:46:33 volumiopi sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:33 volumiopi volumio-remote-updater[568]: [2025-12-21 14:46:33] [connect] Successful connection Dec 21 14:46:33 volumiopi volumio[961]: info: Ignoring mount for partition: boot Dec 21 14:46:33 volumiopi volumio[961]: info: Ignoring mount for partition: volumio Dec 21 14:46:33 volumiopi volumio[961]: info: Ignoring mount for partition: volumio_data Dec 21 14:46:33 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 14:46:33 volumiopi volumio[961]: info: Loading plugin "volumio_command_line_client"... Dec 21 14:46:33 volumiopi volumio[961]: info: Loading plugin "upnp"... Dec 21 14:46:33 volumiopi volumio[961]: info: [1766357193922] Starting Upmpd Daemon Dec 21 14:46:33 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 14:46:33 volumiopi volumio[961]: info: Loading plugin "my_music"... Dec 21 14:46:33 volumiopi volumio[961]: info: Loading plugin "mpd"... Dec 21 14:46:34 volumiopi kernel: Key type cifs.spnego registered Dec 21 14:46:34 volumiopi kernel: Key type cifs.idmap registered Dec 21 14:46:34 volumiopi 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. Dec 21 14:46:34 volumiopi kernel: CIFS: Attempting to mount //192.168.1.57/Music Dec 21 14:46:34 volumiopi sudo[1053]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:34 volumiopi kernel: Malformed UNC in devname Dec 21 14:46:34 volumiopi kernel: CIFS: VFS: Malformed UNC in devname Dec 21 14:46:34 volumiopi volumio[961]: info: Loading plugin "upnp_browser"... Dec 21 14:46:35 volumiopi volumio[961]: info: Starting UPNP Browser Dec 21 14:46:35 volumiopi volumio[961]: info: Loading plugin "alarm-clock"... Dec 21 14:46:36 volumiopi volumio[961]: info: Loading plugin "airplay_emulation"... Dec 21 14:46:36 volumiopi volumio[961]: info: Starting Shairport Sync Dec 21 14:46:36 volumiopi volumio[961]: info: Loading plugin "last_100"... Dec 21 14:46:36 volumiopi volumio[961]: info: Loading plugin "webradio"... Dec 21 14:46:36 volumiopi volumio[961]: info: Loading plugin "i2s_dacs"... Dec 21 14:46:36 volumiopi volumio[961]: info: I2S DAC not set, start Auto-detection Dec 21 14:46:36 volumiopi volumio[961]: info: Loading plugin "volumiodiscovery"... Dec 21 14:46:36 volumiopi volumio[961]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 14:46:36 volumiopi volumio[961]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 14:46:36 volumiopi volumio[961]: *** WARNING *** For more information see Dec 21 14:46:36 volumiopi node[961]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 21 14:46:36 volumiopi volumio[961]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 14:46:36 volumiopi volumio[961]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 14:46:36 volumiopi volumio[961]: *** WARNING *** For more information see Dec 21 14:46:36 volumiopi node[961]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 14:46:36 volumiopi node[961]: *** WARNING *** For more information see Dec 21 14:46:36 volumiopi node[961]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 21 14:46:36 volumiopi node[961]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 21 14:46:36 volumiopi node[961]: *** WARNING *** For more information see Dec 21 14:46:36 volumiopi volumio[961]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 21 14:46:36 volumiopi volumio[961]: info: Discovery: Started advertising with name: VolumioPi Dec 21 14:46:36 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 21 14:46:36 volumiopi volumio[961]: info: Loading plugin "youtube2"... Dec 21 14:46:37 volumiopi sudo[1026]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:40 volumiopi sudo[1043]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:40 volumiopi kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 21 14:46:40 volumiopi kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Dec 21 14:46:40 volumiopi kernel: CIFS: Attempting to mount //192.168.1.8/D Dec 21 14:46:40 volumiopi kernel: CIFS: Attempting to mount //192.168.1.25/Music Dec 21 14:46:40 volumiopi sudo[1045]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:40 volumiopi sudo[1049]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:44 volumiopi volumio[961]: info: Loading plugin "ytcr"... Dec 21 14:46:48 volumiopi volumio-remote-updater[568]: [2025-12-21 14:46:48] [connect] Successful connection Dec 21 14:46:49 volumiopi kernel: hwmon hwmon1: Voltage normalised Dec 21 14:46:51 volumiopi kernel: hwmon hwmon1: Undervoltage detected! Dec 21 14:46:51 volumiopi volumio[961]: info: Loading plugin "outputs"... Dec 21 14:46:51 volumiopi volumio[961]: info: Loading plugin "albumart"... Dec 21 14:46:51 volumiopi volumio[961]: info: Plugin example_plugin is not enabled Dec 21 14:46:51 volumiopi volumio[961]: info: Loading plugin "inputs"... Dec 21 14:46:51 volumiopi volumio[961]: info: Loading plugin "updater_comm"... Dec 21 14:46:52 volumiopi volumio[961]: info: Plugin mpdemulation is not enabled Dec 21 14:46:52 volumiopi volumio[961]: info: Loading plugin "rest_api"... Dec 21 14:46:52 volumiopi volumio[961]: info: Loading plugin "websocket"... Dec 21 14:46:52 volumiopi volumio[961]: info: Starting Socket.io Server version 2.3.0 Dec 21 14:46:52 volumiopi volumio[961]: info: Loading plugin "RoonBridge"... Dec 21 14:46:52 volumiopi volumio[961]: Forking 3 albumart workers Dec 21 14:46:53 volumiopi volumio[961]: info: Applying required configuration parameters for plugin RoonBridge Dec 21 14:46:53 volumiopi volumio[961]: info: Loading i18n strings for locale zh Dec 21 14:46:53 volumiopi volumio[961]: Updating browse sources language Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::initPlayerControls Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 14:46:53 volumiopi volumio[961]: Express server listening on port 3000 Dec 21 14:46:53 volumiopi volumio[961]: [Metrics] WebUI: 27s 457.29ms Dec 21 14:46:53 volumiopi volumio[961]: info: CoreStateMachine::resetVolumioState Dec 21 14:46:53 volumiopi volumio[961]: info: CoreStateMachine::getcurrentVolume Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 14:46:53 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 14:46:54 volumiopi sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 14:46:54 volumiopi sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:54 volumiopi sudo[1162]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:54 volumiopi sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 14:46:54 volumiopi sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:54 volumiopi sudo[1165]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:54 volumiopi volumio[961]: info: Volumio Network Manager: Network status updated: 2 Dec 21 14:46:54 volumiopi volumio[961]: Starting albumart workers Dec 21 14:46:54 volumiopi volumio[961]: Starting albumart workers Dec 21 14:46:54 volumiopi volumio[961]: Starting albumart workers Dec 21 14:46:54 volumiopi volumio[961]: info: Cannot mount NAS ASUS at system boot, trial number 1 ,retrying in 5 seconds Dec 21 14:46:54 volumiopi volumio[961]: info: Cannot mount NAS NAS496E9E at system boot, trial number 1 ,retrying in 5 seconds Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 14:46:54 volumiopi volumio[961]: info: Discovery: Getting this device information Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:54 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 14:46:54 volumiopi volumio[961]: info: Discovery: Getting this device information Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:54 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 14:46:54 volumiopi volumio[961]: info: Discovery: Getting this device information Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:54 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 14:46:54 volumiopi volumio[961]: info: VolumeController:: Volume=100 Mute =false Dec 21 14:46:54 volumiopi volumio[961]: info: CoreStateMachine::pushState Dec 21 14:46:54 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::volumioPushState Dec 21 14:46:54 volumiopi volumio[961]: info: CoreStateMachine::updateTrackBlock Dec 21 14:46:54 volumiopi volumio[961]: info: CorePlayQueue::getTrackBlock Dec 21 14:46:54 volumiopi volumio[961]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 14:46:55 volumiopi volumio[961]: info: Reloading queue from file Dec 21 14:46:55 volumiopi volumio[961]: info: Setting Device type: Raspberry PI Dec 21 14:46:55 volumiopi volumio[961]: info: CoreStateMachine::setRepeat null single undefined Dec 21 14:46:55 volumiopi volumio[961]: info: CoreStateMachine::pushState Dec 21 14:46:55 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:55 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 14:46:55 volumiopi volumio[961]: info: CoreCommandRouter::volumioPushState Dec 21 14:46:55 volumiopi volumio[961]: info: CoreStateMachine::setRandom null Dec 21 14:46:55 volumiopi volumio[961]: info: CoreStateMachine::pushState Dec 21 14:46:55 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:55 volumiopi volumio[961]: info: CoreCommandRouter::volumioPushState Dec 21 14:46:55 volumiopi sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 21 14:46:55 volumiopi sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:55 volumiopi volumio[961]: info: VolumeController:: Volume=100 Mute =false Dec 21 14:46:55 volumiopi volumio[961]: info: CoreStateMachine::pushState Dec 21 14:46:55 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:55 volumiopi volumio[961]: info: CoreCommandRouter::volumioPushState Dec 21 14:46:55 volumiopi volumio[961]: info: Completed loading Core Plugins Dec 21 14:46:55 volumiopi volumio[961]: info: Preparing to generate the ALSA configuration file Dec 21 14:46:55 volumiopi volumio[961]: info: Asound.conf file unchanged, so no further update is needed Dec 21 14:46:55 volumiopi volumio[961]: info: Output device has changed, restarting MPD Dec 21 14:46:55 volumiopi systemd[1]: Started UPnP Renderer front-end to MPD. Dec 21 14:46:55 volumiopi sudo[1176]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:55 volumiopi volumio[961]: info: Output device has changed, restarting Shairport Sync Dec 21 14:46:55 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:55 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 14:46:55 volumiopi sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 14:46:55 volumiopi sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:55 volumiopi sudo[1184]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:55 volumiopi sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 14:46:55 volumiopi sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:55 volumiopi volumio[961]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 14:46:55 volumiopi volumio[961]: info: ___________ START PLUGINS ___________ Dec 21 14:46:55 volumiopi volumio[961]: info: ControllerMpd::onStart: Initializing MPD Dec 21 14:46:55 volumiopi volumio[961]: info: Creating MPD Configuration file Dec 21 14:46:55 volumiopi systemd[1]: Listening on mpd.socket. Dec 21 14:46:55 volumiopi sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 21 14:46:55 volumiopi sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:55 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 14:46:55 volumiopi systemd[1]: Starting Music Player Daemon... Dec 21 14:46:55 volumiopi volumio[961]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 14:46:55 volumiopi volumio[961]: info: [1766357215955] CoreMusicLibrary::Adding element 媒体服务器 Dec 21 14:46:55 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 14:46:55 volumiopi sudo[1214]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:55 volumiopi volumio[961]: info: UPNP Browser: Client initialized successfully Dec 21 14:46:55 volumiopi sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 21 14:46:55 volumiopi sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 14:46:56 volumiopi systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 21 14:46:56 volumiopi systemd[1]: mpd.service: Succeeded. Dec 21 14:46:56 volumiopi systemd[1]: Stopped Music Player Daemon. Dec 21 14:46:56 volumiopi systemd[1]: Starting Music Player Daemon... Dec 21 14:46:56 volumiopi volumio[961]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 14:46:56 volumiopi volumio[961]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 14:46:56 volumiopi volumio[961]: info: [1766357216227] CoreMusicLibrary::Adding element Last_100 Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 14:46:56 volumiopi volumio[961]: info: [1766357216233] CoreMusicLibrary::Adding element Webradio Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 14:46:56 volumiopi volumio[961]: info: Initializing BBC Radios Dec 21 14:46:56 volumiopi sudo[1221]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 21 14:46:56 volumiopi sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:56 volumiopi sudo[1221]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 21 14:46:56 volumiopi sudo[1221]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 21 14:46:56 volumiopi volumio[961]: info: [1766357216457] CoreMusicLibrary::Adding element YouTube2 Dec 21 14:46:56 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 14:46:56 volumiopi volumio[961]: Cannot find translation for source YouTube2 Dec 21 14:46:56 volumiopi volumio[961]: info: Volumio Calling Home Dec 21 14:46:56 volumiopi sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Dec 21 14:46:56 volumiopi sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:56 volumiopi sudo[1240]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:56 volumiopi volumio[961]: verbose: [yt-cast-receiver] Setting app pid to stored value: aee04145-2749-4a87-bc2f-0ddcc3684813 Dec 21 14:46:56 volumiopi volumio[961]: verbose: [yt-cast-receiver] Starting YouTubeApp... Dec 21 14:46:56 volumiopi volumio[961]: verbose: [yt-cast-receiver] (YouTube) Configuring session with stored MDX context: Dec 21 14:46:56 volumiopi volumio[961]: { Dec 21 14:46:56 volumiopi volumio[961]: deviceId: '9079eb37-9b33-4ef4-99fa-b9a52e429937', Dec 21 14:46:56 volumiopi volumio[961]: screenId: 'fe71e975mupmpa0c1g4po4sf6v' Dec 21 14:46:56 volumiopi volumio[961]: } Dec 21 14:46:56 volumiopi volumio[961]: verbose: [yt-cast-receiver] (YouTube Music) Configuring session with stored MDX context: Dec 21 14:46:56 volumiopi volumio[961]: { Dec 21 14:46:56 volumiopi volumio[961]: deviceId: '73d21b68-b65d-4d7f-96f8-b0522fc86906', Dec 21 14:46:56 volumiopi volumio[961]: screenId: 'frqrdi1gu77teccpopq04onifs' Dec 21 14:46:56 volumiopi volumio[961]: } Dec 21 14:46:57 volumiopi volumio[961]: info: Discovery: adding 5f0688b2-2146-4107-b220-9ad3fbc85c35 Dec 21 14:46:57 volumiopi volumio[961]: info: Discovery: Found device VolumioPi Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:57 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:57 volumiopi volumio[961]: error: Cannot start Roon Bridge Error: Command failed: /usr/bin/sudo /bin/systemctl start roonbridge.service Dec 21 14:46:57 volumiopi volumio[961]: Failed to start roonbridge.service: Unit roonbridge.service not found. Dec 21 14:46:57 volumiopi volumio[961]: info: MPD Permissions set Dec 21 14:46:57 volumiopi volumio[961]: info: MPD Permissions set Dec 21 14:46:57 volumiopi volumio[961]: info: Upmpdcli Daemon Started Dec 21 14:46:57 volumiopi sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 14:46:57 volumiopi sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:57 volumiopi sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 14:46:57 volumiopi sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:57 volumiopi sudo[1246]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:57 volumiopi volumio[961]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 21 14:46:57 volumiopi sudo[1248]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:57 volumiopi volumio[961]: info: Volumio called home Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 14:46:57 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:57 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:57 volumiopi volumio[961]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.8 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Dec 21 14:46:57 volumiopi volumio[961]: info: Starting Shairport Sync Dec 21 14:46:57 volumiopi volumio[961]: info: Starting Shairport Sync Dec 21 14:46:57 volumiopi volumio[961]: info: Starting Shairport Sync Dec 21 14:46:57 volumiopi sudo[1260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 14:46:57 volumiopi sudo[1260]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:57 volumiopi sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 14:46:57 volumiopi sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:58 volumiopi sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 21 14:46:58 volumiopi sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 14:46:58 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 21 14:46:58 volumiopi systemd[1]: systemd-timedated.service: Succeeded. Dec 21 14:46:58 volumiopi systemd[1]: shairport-sync.service: Succeeded. Dec 21 14:46:58 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 21 14:46:58 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 21 14:46:58 volumiopi sudo[1260]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:58 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 21 14:46:58 volumiopi sudo[1262]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 21 14:46:58 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 21 14:46:58 volumiopi systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 21 14:46:58 volumiopi systemd[1]: shairport-sync.service: Succeeded. Dec 21 14:46:58 volumiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 21 14:46:58 volumiopi volumio[961]: info: Received Get System Info Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 14:46:58 volumiopi volumio[961]: info: Discovery: Getting this device information Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:58 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:58 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:58 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 21 14:46:58 volumiopi sudo[1265]: pam_unix(sudo:session): session closed for user root Dec 21 14:46:58 volumiopi volumio[961]: info: Listing playlists Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 14:46:58 volumiopi volumio[961]: info: Received Get System Info Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 14:46:58 volumiopi volumio[961]: info: Discovery: Getting this device information Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:58 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 14:46:58 volumiopi volumio[961]: info: Shairport-Sync Started Dec 21 14:46:58 volumiopi volumio[961]: Error adding Membership: Error: addMembership EINVAL Dec 21 14:46:58 volumiopi volumio[961]: info: Shairport-Sync Started Dec 21 14:46:58 volumiopi volumio[961]: info: Shairport-Sync Started Dec 21 14:46:58 volumiopi volumio[961]: info: Received Get System Info Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 14:46:58 volumiopi volumio[961]: info: Discovery: Getting this device information Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:58 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:58 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:58 volumiopi volumio[961]: verbose: [yt-cast-receiver] (YouTube) Obtained lounge token: Dec 21 14:46:58 volumiopi volumio[961]: { Dec 21 14:46:58 volumiopi volumio[961]: screenId: 'fe71e975mupmpa0c1g4po4sf6v', Dec 21 14:46:58 volumiopi volumio[961]: refreshIntervalInMillis: 1123200000, Dec 21 14:46:58 volumiopi volumio[961]: remoteRefreshIntervalMs: 79200000, Dec 21 14:46:58 volumiopi volumio[961]: refreshIntervalMs: 1123200000, Dec 21 14:46:58 volumiopi volumio[961]: loungeTokenLifespanMs: 1209600000, Dec 21 14:46:58 volumiopi volumio[961]: loungeToken: 'AGdO5p-Mdqk0KiMGR_8JHwEu0U7Mveh0vK2QKeS4BjY1Lp6kJEDVnGqRh0DZ3LAK7YA5dqZGMK_oGtQRTwJ3QtsehxGk1JOQ5VLiHA8u5zt3NSDNevcnk4A', Dec 21 14:46:58 volumiopi volumio[961]: remoteRefreshIntervalInMillis: 79200000, Dec 21 14:46:58 volumiopi volumio[961]: expiration: 1767480418665 Dec 21 14:46:58 volumiopi volumio[961]: } Dec 21 14:46:58 volumiopi volumio[961]: verbose: [yt-cast-receiver] (YouTube Music) Obtained lounge token: Dec 21 14:46:58 volumiopi volumio[961]: { Dec 21 14:46:58 volumiopi volumio[961]: screenId: 'frqrdi1gu77teccpopq04onifs', Dec 21 14:46:58 volumiopi volumio[961]: refreshIntervalInMillis: 1123200000, Dec 21 14:46:58 volumiopi volumio[961]: remoteRefreshIntervalMs: 79200000, Dec 21 14:46:58 volumiopi volumio[961]: refreshIntervalMs: 1123200000, Dec 21 14:46:58 volumiopi volumio[961]: loungeTokenLifespanMs: 1209600000, Dec 21 14:46:58 volumiopi volumio[961]: loungeToken: 'AGdO5p9ueYNKC7pVUFwsvFVsy3Ptt_W9EYsb5HWyk1_a-BN4sYrpfAB6ZxHmnNTKR3ednRcGZM8-ZOoxFM7S4ZgucFhNBVwIGfbjiyeliaDq1o0sAYC1PIc', Dec 21 14:46:58 volumiopi volumio[961]: remoteRefreshIntervalInMillis: 79200000, Dec 21 14:46:58 volumiopi volumio[961]: expiration: 1767480418760 Dec 21 14:46:58 volumiopi volumio[961]: } Dec 21 14:46:58 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 21 14:46:59 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (YouTube) Received messages for establishing session: Dec 21 14:46:59 volumiopi volumio[961]: [ Dec 21 14:46:59 volumiopi volumio[961]: Message { AID: 0, name: 'c', payload: [ '54432DCC807FF433', '', 8 ] }, Dec 21 14:46:59 volumiopi volumio[961]: Message { Dec 21 14:46:59 volumiopi volumio[961]: AID: 1, Dec 21 14:46:59 volumiopi volumio[961]: name: 'S', Dec 21 14:46:59 volumiopi volumio[961]: payload: 'wPjdpkxaOLEToDAKZEpLYjkZZeqDCR38' Dec 21 14:46:59 volumiopi volumio[961]: }, Dec 21 14:46:59 volumiopi volumio[961]: Message { Dec 21 14:46:59 volumiopi volumio[961]: AID: 2, Dec 21 14:46:59 volumiopi volumio[961]: name: 'loungeStatus', Dec 21 14:46:59 volumiopi volumio[961]: payload: { Dec 21 14:46:59 volumiopi volumio[961]: queueId: 'RQNrEsqld-Qk1H82oLnvcfbBcfySA', Dec 21 14:46:59 volumiopi volumio[961]: devices: '[{"app":"ytcr","capabilities":"dsp,mic,dpa,ntb,que,mus","clientName":"unknown","experiments":"","name":"YouTube on VolumioPi","theme":"cl","id":"9079eb37-9b33-4ef4-99fa-b9a52e429937","type":"LOUNGE_SCREEN","hasCc":"true","deviceInfo":"{\\"year\\":0,\\"os\\":\\"Windows\\",\\"osVersion\\":\\"10.0\\",\\"chipset\\":\\"\\",\\"clientName\\":\\"TVHTML5\\",\\"dialAdditionalDataSupportLevel\\":\\"unsupported\\",\\"mdxDialServerType\\":\\"MDX_DIAL_SERVER_TYPE_UNKNOWN\\",\\"brand\\":\\"Generic\\",\\"model\\":\\"SmartTV\\"}","receiverIdentityMatchStatus":"IS_RECEIVER"}]', Dec 21 14:46:59 volumiopi volumio[961]: connectionEventDetails: '{"deviceId":"9079eb37-9b33-4ef4-99fa-b9a52e429937"}' Dec 21 14:46:59 volumiopi volumio[961]: } Dec 21 14:46:59 volumiopi volumio[961]: }, Dec 21 14:46:59 volumiopi volumio[961]: Message { AID: 3, name: 'getNowPlaying', payload: {} }, Dec 21 14:46:59 volumiopi volumio[961]: Message { AID: 4, name: 'getDiscoveryDeviceId', payload: {} } Dec 21 14:46:59 volumiopi volumio[961]: ] Dec 21 14:46:59 volumiopi volumio[961]: verbose: ----------------------------------- Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (AID: 2) (YouTube) Incoming message: 'loungeStatus' Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=3&CI=0&RID=rpc&SID=54432DCC807FF433&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=wPjdpkxaOLEToDAKZEpLYjkZZeqDCR38&id=9079eb37-9b33-4ef4-99fa-b9a52e429937&loungeIdToken=AGdO5p-Mdqk0KiMGR_8JHwEu0U7Mveh0vK2QKeS4BjY1Lp6kJEDVnGqRh0DZ3LAK7YA5dqZGMK_oGtQRTwJ3QtsehxGk1JOQ5VLiHA8u5zt3NSDNevcnk4A&mdxVersion=2&name=YouTube%20on%20VolumioPi&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=057bf3182a0f Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (YouTube Music) Received messages for establishing session: Dec 21 14:46:59 volumiopi volumio[961]: [ Dec 21 14:46:59 volumiopi volumio[961]: Message { AID: 0, name: 'c', payload: [ 'D4C36119BEB4D12F', '', 8 ] }, Dec 21 14:46:59 volumiopi volumio[961]: Message { Dec 21 14:46:59 volumiopi volumio[961]: AID: 1, Dec 21 14:46:59 volumiopi volumio[961]: name: 'S', Dec 21 14:46:59 volumiopi volumio[961]: payload: 'FbBAOwytMEHzvp8viKkZJCySSf66ZD9U' Dec 21 14:46:59 volumiopi volumio[961]: }, Dec 21 14:46:59 volumiopi volumio[961]: Message { Dec 21 14:46:59 volumiopi volumio[961]: AID: 2, Dec 21 14:46:59 volumiopi volumio[961]: name: 'loungeStatus', Dec 21 14:46:59 volumiopi volumio[961]: payload: { Dec 21 14:46:59 volumiopi volumio[961]: queueId: 'RQ1a7DZtWrnjhsHYS9qJsOcs6Nwo4', Dec 21 14:46:59 volumiopi volumio[961]: devices: '[{"app":"ytcr","capabilities":"dsp,mic,dpa,ntb","clientName":"unknown","experiments":"","name":"YouTube on VolumioPi","theme":"m","id":"73d21b68-b65d-4d7f-96f8-b0522fc86906","type":"LOUNGE_SCREEN","hasCc":"true","deviceInfo":"{\\"year\\":0,\\"os\\":\\"Windows\\",\\"osVersion\\":\\"10.0\\",\\"chipset\\":\\"\\",\\"clientName\\":\\"TVHTML5\\",\\"dialAdditionalDataSupportLevel\\":\\"unsupported\\",\\"mdxDialServerType\\":\\"MDX_DIAL_SERVER_TYPE_UNKNOWN\\",\\"brand\\":\\"Generic\\",\\"model\\":\\"SmartTV\\"}","receiverIdentityMatchStatus":"IS_RECEIVER"}]', Dec 21 14:46:59 volumiopi volumio[961]: connectionEventDetails: '{"deviceId":"73d21b68-b65d-4d7f-96f8-b0522fc86906"}' Dec 21 14:46:59 volumiopi volumio[961]: } Dec 21 14:46:59 volumiopi volumio[961]: }, Dec 21 14:46:59 volumiopi volumio[961]: Message { AID: 3, name: 'getNowPlaying', payload: {} }, Dec 21 14:46:59 volumiopi volumio[961]: Message { AID: 4, name: 'getDiscoveryDeviceId', payload: {} } Dec 21 14:46:59 volumiopi volumio[961]: ] Dec 21 14:46:59 volumiopi volumio[961]: verbose: ----------------------------------- Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (AID: 2) (YouTube Music) Incoming message: 'loungeStatus' Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=3&CI=0&RID=rpc&SID=D4C36119BEB4D12F&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=FbBAOwytMEHzvp8viKkZJCySSf66ZD9U&id=73d21b68-b65d-4d7f-96f8-b0522fc86906&loungeIdToken=AGdO5p9ueYNKC7pVUFwsvFVsy3Ptt_W9EYsb5HWyk1_a-BN4sYrpfAB6ZxHmnNTKR3ednRcGZM8-ZOoxFM7S4ZgucFhNBVwIGfbjiyeliaDq1o0sAYC1PIc&mdxVersion=2&name=YouTube%20on%20VolumioPi&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=52de091d51b2 Dec 21 14:46:59 volumiopi volumio[961]: info: Cannot mount NAS ASUS at system boot, trial number 2 ,retrying in 5 seconds Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] RPC connection established. Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (YouTube) Session established. Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (YouTube) Saving MDX context to data store: Dec 21 14:46:59 volumiopi volumio[961]: { Dec 21 14:46:59 volumiopi volumio[961]: deviceId: '9079eb37-9b33-4ef4-99fa-b9a52e429937', Dec 21 14:46:59 volumiopi volumio[961]: screenId: 'fe71e975mupmpa0c1g4po4sf6v' Dec 21 14:46:59 volumiopi volumio[961]: } Dec 21 14:46:59 volumiopi volumio[961]: verbose: ----------------------------------- Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (AID: 4) (YouTube) Incoming message: 'getDiscoveryDeviceId' Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (AID: 4) (YouTube) Not handled: 'getDiscoveryDeviceId' Dec 21 14:46:59 volumiopi sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=leon_wang21,password=Eaxd983a86,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.57/Music /mnt/NAS/NAS496E9E Dec 21 14:46:59 volumiopi sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] RPC connection established. Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (YouTube Music) Session established. Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (YouTube Music) Saving MDX context to data store: Dec 21 14:46:59 volumiopi volumio[961]: { Dec 21 14:46:59 volumiopi volumio[961]: deviceId: '73d21b68-b65d-4d7f-96f8-b0522fc86906', Dec 21 14:46:59 volumiopi volumio[961]: screenId: 'frqrdi1gu77teccpopq04onifs' Dec 21 14:46:59 volumiopi volumio[961]: } Dec 21 14:46:59 volumiopi kernel: CIFS: Attempting to mount //192.168.1.57/Music Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] Starting DIAL server... Dec 21 14:46:59 volumiopi volumio[961]: info: [yt-cast-receiver] DIAL server listening on port 8098 Dec 21 14:46:59 volumiopi volumio[961]: info: CoreCommandRouter::volumioRetrievevolume Dec 21 14:46:59 volumiopi volumio[961]: verbose: ----------------------------------- Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (AID: 4) (YouTube Music) Incoming message: 'getDiscoveryDeviceId' Dec 21 14:46:59 volumiopi volumio[961]: verbose: [yt-cast-receiver] (AID: 4) (YouTube Music) Not handled: 'getDiscoveryDeviceId' Dec 21 14:46:59 volumiopi volumio[961]: info: VolumeController:: Volume=100 Mute =false Dec 21 14:46:59 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetState Dec 21 14:46:59 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:59 volumiopi volumio[961]: info: CoreStateMachine::pushState Dec 21 14:46:59 volumiopi volumio[961]: info: CorePlayQueue::getTrack 0 Dec 21 14:46:59 volumiopi volumio[961]: info: CoreCommandRouter::volumioPushState Dec 21 14:46:59 volumiopi volumio[961]: verbose: [ytcr] VolumeControl initialized with current volume: Dec 21 14:46:59 volumiopi volumio[961]: { level: 100, muted: false } Dec 21 14:47:00 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 21 14:47:01 volumiopi mpd[1231]: Dec 21 14:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 21 14:47:02 volumiopi systemd[1]: Started Music Player Daemon. Dec 21 14:47:02 volumiopi sudo[1216]: pam_unix(sudo:session): session closed for user root Dec 21 14:47:02 volumiopi sudo[1195]: pam_unix(sudo:session): session closed for user root Dec 21 14:47:02 volumiopi volumio[961]: Upnp client error: Error: This socket has been ended by the other party Dec 21 14:47:02 volumiopi volumio[961]: Upnp client error: Error: This socket has been ended by the other party Dec 21 14:47:02 volumiopi volumio[961]: error: MPD error: The expression evaluated to a falsy value: Dec 21 14:47:02 volumiopi volumio[961]: assert.ok(self.idling) Dec 21 14:47:02 volumiopi volumio[961]: error: The expression evaluated to a falsy value: Dec 21 14:47:02 volumiopi volumio[961]: assert.ok(self.idling) Dec 21 14:47:02 volumiopi volumio[961]: error: updateQueue error: null Dec 21 14:47:02 volumiopi volumio[961]: info: MPD running with PID1231 Dec 21 14:47:02 volumiopi volumio[961]: ,establishing connection Dec 21 14:47:02 volumiopi volumio[961]: verbose: [ytcr] MPD connected Dec 21 14:47:02 volumiopi volumio[961]: verbose: [ytcr] MPDSubsystemEventEmitter enabled. Dec 21 14:47:02 volumiopi volumio[961]: verbose: [ytcr] Receiver started with options: Dec 21 14:47:02 volumiopi volumio[961]: { Dec 21 14:47:02 volumiopi volumio[961]: dial: { port: 8098, bindToInterfaces: [ 'wlan0' ] }, Dec 21 14:47:02 volumiopi volumio[961]: app: { Dec 21 14:47:02 volumiopi volumio[961]: enableAutoplayOnConnect: true, Dec 21 14:47:02 volumiopi volumio[961]: resetPlayerOnDisconnectPolicy: 'allDisconnected' Dec 21 14:47:02 volumiopi volumio[961]: }, Dec 21 14:47:02 volumiopi volumio[961]: dataStore: ReceiverDataStore {}, Dec 21 14:47:02 volumiopi volumio[961]: logger: Logger { level: 'debug', color: true }, Dec 21 14:47:02 volumiopi volumio[961]: logLevel: 'debug', Dec 21 14:47:02 volumiopi volumio[961]: device: { name: 'VolumioPi' } Dec 21 14:47:02 volumiopi volumio[961]: } Dec 21 14:47:02 volumiopi volumio[961]: info: Completed starting Core Plugins Dec 21 14:47:02 volumiopi volumio[961]: info: ------------------------------------------- Dec 21 14:47:02 volumiopi volumio[961]: info: ----- MyVolumio plugins startup ---- Dec 21 14:47:02 volumiopi volumio[961]: info: ------------------------------------------- Dec 21 14:47:02 volumiopi volumio[961]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 21 14:47:02 volumiopi volumio[961]: error: updateQueue error: null Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetBrowseSources Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetBrowseSources Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::volumioGetBrowseSources Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 14:47:02 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 21 14:47:03 volumiopi volumio-remote-updater[568]: [2025-12-21 14:47:03] [connect] Successful connection Dec 21 14:47:03 volumiopi volumio-remote-updater[568]: [2025-12-21 14:47:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766357223 101 Dec 21 14:47:03 volumiopi volumio[961]: 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: 3 Dec 21 14:47:04 volumiopi volumio[961]: info: Cannot mount NAS ASUS at system boot, trial number 3 ,retrying in 5 seconds Dec 21 14:47:05 volumiopi sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 21 14:47:05 volumiopi sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:47:05 volumiopi sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 21 14:47:05 volumiopi sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:47:05 volumiopi systemd[1]: Started MPD Monitor Service. Dec 21 14:47:05 volumiopi sudo[1348]: pam_unix(sudo:session): session closed for user root Dec 21 14:47:05 volumiopi volumio[961]: info: Successfully started MPD Monitor Dec 21 14:47:05 volumiopi systemd[1]: Stopping MPD Monitor Service... Dec 21 14:47:05 volumiopi systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 21 14:47:05 volumiopi systemd[1]: mpd_monitor.service: Succeeded. Dec 21 14:47:05 volumiopi systemd[1]: Stopped MPD Monitor Service. Dec 21 14:47:05 volumiopi systemd[1]: Started MPD Monitor Service. Dec 21 14:47:05 volumiopi sudo[1350]: pam_unix(sudo:session): session closed for user root Dec 21 14:47:05 volumiopi volumio[961]: info: Successfully started MPD Monitor Dec 21 14:47:05 volumiopi mpd_monitor.sh[1354]: MPD Monitor Service: Starting MPD Monitor Service Dec 21 14:47:05 volumiopi kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 21 14:47:05 volumiopi kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Dec 21 14:47:05 volumiopi sudo[1276]: pam_unix(sudo:session): session closed for user root Dec 21 14:47:05 volumiopi volumio[961]: info: Cannot mount NAS NAS496E9E at system boot, trial number 2 ,retrying in 5 seconds Dec 21 14:47:05 volumiopi volumio[1178]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 21 14:47:06 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 14:47:07 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 21 14:47:08 volumiopi volumio[961]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 21 14:47:09 volumiopi ntpd[755]: Soliciting pool server 185.234.20.134 Dec 21 14:47:09 volumiopi ntpd[755]: Soliciting pool server 198.137.202.56 Dec 21 14:47:09 volumiopi volumio[961]: info: Cannot mount NAS ASUS at system boot, trial number 4 ,retrying in 5 seconds Dec 21 14:47:09 volumiopi ntpd[755]: Soliciting pool server 23.186.168.126 Dec 21 14:47:09 volumiopi ntpd[755]: Soliciting pool server 172.234.25.10 Dec 21 14:47:10 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Dec 21 14:47:10 volumiopi sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/ASUS Dec 21 14:47:10 volumiopi sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:47:10 volumiopi sudo[1374]: pam_unix(sudo:session): session closed for user root Dec 21 14:47:10 volumiopi systemd[1]: mnt-NAS-ASUS.mount: Succeeded. Dec 21 14:47:10 volumiopi ntpd[755]: Soliciting pool server 64.246.132.14 Dec 21 14:47:10 volumiopi ntpd[755]: Soliciting pool server 23.186.168.131 Dec 21 14:47:11 volumiopi volumio[961]: info: Share ASUS successfully unmounted Dec 21 14:47:11 volumiopi sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=leon_wang21,password=Eaxd983a86,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.57/Music /mnt/NAS/NAS496E9E Dec 21 14:47:11 volumiopi sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 14:47:11 volumiopi kernel: CIFS: Attempting to mount //192.168.1.57/Music Dec 21 14:47:11 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 14:47:11 volumiopi ntpd[755]: Soliciting pool server 23.186.168.124 Dec 21 14:47:11 volumiopi ntpd[755]: Soliciting pool server 23.142.248.9 Dec 21 14:47:13 volumiopi ntpd[755]: Soliciting pool server 216.229.4.66 Dec 21 14:47:13 volumiopi ntpd[755]: Soliciting pool server 173.230.154.254 Dec 21 14:47:13 volumiopi ntpd[755]: Soliciting pool server 207.58.172.126 Dec 21 14:47:13 volumiopi ntpd[755]: Soliciting pool server 141.11.228.173 Dec 21 14:47:13 volumiopi ntpd[755]: Soliciting pool server 172.233.157.223 Dec 21 14:47:13 volumiopi ntpd[755]: Soliciting pool server 172.233.155.39 Dec 21 14:47:13 volumiopi ntpd[755]: Soliciting pool server 172.104.28.175 Dec 21 14:47:13 volumiopi ntpd[755]: Soliciting pool server 38.45.64.130 Dec 21 14:47:14 volumiopi volumio[961]: Cannot compose Albumart path Dec 21 14:47:14 volumiopi ntpd[755]: Soliciting pool server 172.235.154.118 Dec 21 14:47:14 volumiopi ntpd[755]: Soliciting pool server 51.81.226.229 Dec 21 14:47:14 volumiopi ntpd[755]: Soliciting pool server 23.186.168.132 Dec 21 14:47:14 volumiopi ntpd[755]: Soliciting pool server 12.205.28.193 Dec 21 14:47:15 volumiopi volumio[961]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 21 14:47:15 volumiopi volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 14:47:15 volumiopi volumio[961]: TypeError: Cannot read property 'replace' of undefined Dec 21 14:47:15 volumiopi volumio[961]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Dec 21 14:47:15 volumiopi volumio[961]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Dec 21 14:47:15 volumiopi volumio[961]: at listOnTimeout (internal/timers.js:554:17) Dec 21 14:47:15 volumiopi volumio[961]: at processTimers (internal/timers.js:497:7) Dec 21 14:47:15 volumiopi volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 14:47:16 volumiopi sudo[1417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-21 14:46 Dec 21 14:47:16 volumiopi sudo[1417]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"