-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Wed 2025-07-16 15:25:33 CST. -- Jul 16 15:24:30 volumio systemd-timedated[1019]: Changed local time to Wed Jul 16 15:24:30 2025 Jul 16 15:24:30 volumio sudo[1017]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:30 volumio volumio-time-update[549]: volumio-time-update-util: System time updated successfully. Jul 16 15:24:30 volumio systemd[1]: Starting Daily apt download activities... Jul 16 15:24:30 volumio systemd[1]: Started Volumio Time Update Utility. Jul 16 15:24:30 volumio systemd[1]: Reached target Multi-User System. Jul 16 15:24:30 volumio systemd[1]: Reached target Graphical Interface. Jul 16 15:24:30 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 16 15:24:30 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 16 15:24:30 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 16 15:24:30 volumio nmbd[724]: [2025/07/16 15:24:30.296505, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 16 15:24:30 volumio nmbd[724]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.58 for name WORKGROUP<1d>. Jul 16 15:24:30 volumio nmbd[724]: This response was from IP 192.168.3.68, reporting an IP address of 192.168.3.68. Jul 16 15:24:30 volumio volumio[969]: info: Loading plugin "network"... Jul 16 15:24:30 volumio volumio[969]: info: Refreshing Cached IP Addresses Jul 16 15:24:30 volumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 16 15:24:30 volumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:30 volumio sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 16 15:24:30 volumio sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:30 volumio sudo[1043]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:30 volumio volumio[969]: info: Loading plugin "services"... Jul 16 15:24:30 volumio sudo[1045]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:30 volumio volumio[969]: info: Loading plugin "alsa_controller"... Jul 16 15:24:30 volumio sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 16 15:24:30 volumio sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:30 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 16 15:24:30 volumio volumio[969]: info: Loading plugin "wizard"... Jul 16 15:24:30 volumio volumio[969]: info: Loading plugin "networkfs"... Jul 16 15:24:30 volumio volumio[969]: info: Starting Udev Watcher for removable devices Jul 16 15:24:30 volumio sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=SA6400,password=aA123456,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.62/music /mnt/NAS/S\'A6400 Jul 16 15:24:30 volumio sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:30 volumio sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ho,password=hh378378,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.3.68/music /mnt/NAS/Fnos Jul 16 15:24:30 volumio sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:30 volumio volumio[969]: info: Ignoring mount for partition: boot Jul 16 15:24:30 volumio volumio[969]: info: Ignoring mount for partition: volumio Jul 16 15:24:30 volumio volumio[969]: info: Ignoring mount for partition: volumio_data Jul 16 15:24:30 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 16 15:24:30 volumio volumio[969]: info: Loading plugin "volumio_command_line_client"... Jul 16 15:24:30 volumio volumio[969]: info: Loading plugin "upnp"... Jul 16 15:24:31 volumio volumio[969]: info: [1752650671002] Starting Upmpd Daemon Jul 16 15:24:31 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 16 15:24:31 volumio volumio[969]: info: Loading plugin "my_music"... Jul 16 15:24:31 volumio volumio[969]: info: Loading plugin "mpd"... Jul 16 15:24:31 volumio kernel: Key type cifs.spnego registered Jul 16 15:24:31 volumio kernel: Key type cifs.idmap registered Jul 16 15:24:31 volumio kernel: CIFS: Attempting to mount //192.168.3.68/music Jul 16 15:24:31 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. Jul 16 15:24:31 volumio systemd[1]: apt-daily.service: Succeeded. Jul 16 15:24:31 volumio systemd[1]: Started Daily apt download activities. Jul 16 15:24:31 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jul 16 15:24:31 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jul 16 15:24:31 volumio sudo[1056]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:31 volumio sudo[1085]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:31 volumio kernel: CIFS: Attempting to mount //192.168.3.62/music Jul 16 15:24:31 volumio volumio[969]: info: Loading plugin "upnp_browser"... Jul 16 15:24:32 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 16 15:24:32 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jul 16 15:24:32 volumio systemd[1]: Startup finished in 13.948s (kernel) + 24.231s (userspace) = 38.180s. Jul 16 15:24:32 volumio volumio[969]: info: Loading plugin "alarm-clock"... Jul 16 15:24:32 volumio volumio[969]: info: Loading plugin "airplay_emulation"... Jul 16 15:24:32 volumio volumio[969]: info: Starting Shairport Sync Jul 16 15:24:32 volumio volumio[969]: info: Loading plugin "last_100"... Jul 16 15:24:32 volumio volumio[969]: info: Loading plugin "webradio"... Jul 16 15:24:32 volumio volumio[969]: info: Loading plugin "i2s_dacs"... Jul 16 15:24:32 volumio volumio[969]: info: I2S DAC not set, start Auto-detection Jul 16 15:24:32 volumio volumio[969]: info: Loading plugin "volumiodiscovery"... Jul 16 15:24:32 volumio volumio[969]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 16 15:24:32 volumio volumio[969]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 16 15:24:32 volumio volumio[969]: *** WARNING *** For more information see Jul 16 15:24:32 volumio volumio[969]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 16 15:24:32 volumio volumio[969]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 16 15:24:32 volumio volumio[969]: *** WARNING *** For more information see Jul 16 15:24:32 volumio node[969]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 16 15:24:32 volumio node[969]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 16 15:24:32 volumio node[969]: *** WARNING *** For more information see Jul 16 15:24:32 volumio node[969]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 16 15:24:32 volumio node[969]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 16 15:24:32 volumio node[969]: *** WARNING *** For more information see Jul 16 15:24:32 volumio volumio[969]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 16 15:24:32 volumio volumio[969]: info: Discovery: Started advertising with name: Volumio Jul 16 15:24:32 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 16 15:24:32 volumio volumio[969]: info: Loading plugin "outputs"... Jul 16 15:24:32 volumio volumio[969]: info: Loading plugin "albumart"... Jul 16 15:24:32 volumio volumio[969]: info: Plugin example_plugin is not enabled Jul 16 15:24:32 volumio volumio[969]: info: Loading plugin "inputs"... Jul 16 15:24:32 volumio volumio[969]: info: Loading plugin "updater_comm"... Jul 16 15:24:33 volumio volumio[969]: info: Plugin mpdemulation is not enabled Jul 16 15:24:33 volumio volumio[969]: info: Loading plugin "rest_api"... Jul 16 15:24:33 volumio volumio[969]: info: Loading plugin "websocket"... Jul 16 15:24:33 volumio volumio[969]: info: Starting Socket.io Server version 2.3.0 Jul 16 15:24:33 volumio volumio[969]: info: Loading i18n strings for locale zh_TW Jul 16 15:24:33 volumio volumio[969]: Updating browse sources language Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 15:24:33 volumio volumio[969]: Forking 3 albumart workers Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::initPlayerControls Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 15:24:33 volumio volumio[969]: Express server listening on port 3000 Jul 16 15:24:33 volumio volumio[969]: [Metrics] WebUI: 7s 354.64ms Jul 16 15:24:33 volumio volumio[969]: info: CoreStateMachine::resetVolumioState Jul 16 15:24:33 volumio volumio[969]: info: CoreStateMachine::getcurrentVolume Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::volumioRetrievevolume Jul 16 15:24:33 volumio volumio[969]: info: CoreStateMachine::pushState Jul 16 15:24:33 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::volumioPushState Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:24:33 volumio volumio[969]: info: Volumio Network Manager: Network status updated: 2 Jul 16 15:24:33 volumio volumio[969]: info: Reloading queue from file Jul 16 15:24:33 volumio volumio[969]: info: CoreStateMachine::setRepeat null single undefined Jul 16 15:24:33 volumio volumio[969]: info: CoreStateMachine::pushState Jul 16 15:24:33 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::volumioPushState Jul 16 15:24:33 volumio volumio[969]: info: CoreStateMachine::setRandom null Jul 16 15:24:33 volumio volumio[969]: info: CoreStateMachine::pushState Jul 16 15:24:33 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:24:33 volumio volumio[969]: info: CoreCommandRouter::volumioPushState Jul 16 15:24:33 volumio volumio[969]: info: Setting Device type: Raspberry PI Jul 16 15:24:34 volumio volumio[969]: info: Discovery: adding 8a6c339f-2230-4f6e-8c30-123092c1db44 Jul 16 15:24:34 volumio volumio[969]: info: Discovery: Found device Volumio Jul 16 15:24:34 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:24:34 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:24:34 volumio volumio[969]: Starting albumart workers Jul 16 15:24:34 volumio volumio[969]: Starting albumart workers Jul 16 15:24:34 volumio volumio[969]: Starting albumart workers Jul 16 15:24:36 volumio volumio-remote-updater[568]: [2025-07-16 15:24:36] [connect] Successful connection Jul 16 15:24:36 volumio volumio-remote-updater[568]: [2025-07-16 15:24:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752650676 101 Jul 16 15:24:36 volumio volumio[969]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jul 16 15:24:37 volumio sudo[1083]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:37 volumio volumio[969]: info: Cannot mount NAS S'A6400 at system boot, trial number 1 ,retrying in 5 seconds Jul 16 15:24:37 volumio volumio[969]: info: Completed loading Core Plugins Jul 16 15:24:37 volumio volumio[969]: info: Preparing to generate the ALSA configuration file Jul 16 15:24:37 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 16 15:24:37 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 16 15:24:37 volumio volumio[969]: info: Asound.conf file unchanged, so no further update is needed Jul 16 15:24:37 volumio volumio[969]: info: Output device has changed, restarting MPD Jul 16 15:24:38 volumio volumio[969]: info: Output device has changed, restarting Shairport Sync Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:24:38 volumio sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 16 15:24:38 volumio sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:38 volumio sudo[1254]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:38 volumio sudo[1256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 16 15:24:38 volumio sudo[1256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:38 volumio systemd[1]: Stopping Music Player Daemon... Jul 16 15:24:38 volumio volumio[969]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 16 15:24:38 volumio volumio[969]: info: ___________ START PLUGINS ___________ Jul 16 15:24:38 volumio volumio[969]: info: ControllerMpd::onStart: Initializing MPD Jul 16 15:24:38 volumio volumio[969]: info: Creating MPD Configuration file Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 15:24:38 volumio sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 16 15:24:38 volumio volumio[969]: info: [1752650678141] CoreMusicLibrary::Adding element Media Servers Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 15:24:38 volumio sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:38 volumio sudo[1263]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:38 volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 16 15:24:38 volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:24:38 volumio systemd[1]: mpd.service: Succeeded. Jul 16 15:24:38 volumio systemd[1]: Stopped Music Player Daemon. Jul 16 15:24:38 volumio volumio[969]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 16 15:24:38 volumio volumio[969]: info: [1752650678287] CoreMusicLibrary::Adding element Last_100 Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 16 15:24:38 volumio volumio[969]: info: [1752650678297] CoreMusicLibrary::Adding element Webradio Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 16 15:24:38 volumio volumio[969]: info: Initializing BBC Radios Jul 16 15:24:38 volumio systemd[1]: Starting Music Player Daemon... Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: Volumio Calling Home Jul 16 15:24:38 volumio sudo[1274]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 16 15:24:38 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:38 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:38 volumio volumio[969]: info: MPD Permissions set Jul 16 15:24:38 volumio volumio[969]: info: MPD Permissions set Jul 16 15:24:38 volumio volumio[969]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:24:38 volumio volumio[969]: info: Starting Shairport Sync Jul 16 15:24:38 volumio volumio[969]: info: Starting Shairport Sync Jul 16 15:24:38 volumio volumio[969]: info: Starting Shairport Sync Jul 16 15:24:38 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 16 15:24:38 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:38 volumio sudo[1303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 16 15:24:38 volumio sudo[1303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:38 volumio sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 16 15:24:38 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:38 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:24:38 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:24:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 16 15:24:38 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 16 15:24:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 16 15:24:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 16 15:24:39 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:39 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:39 volumio sudo[1303]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:39 volumio volumio[969]: info: Shairport-Sync Started Jul 16 15:24:39 volumio volumio[969]: Error adding Membership: Error: addMembership EINVAL Jul 16 15:24:39 volumio volumio[969]: info: Shairport-Sync Started Jul 16 15:24:39 volumio volumio[969]: info: Shairport-Sync Started Jul 16 15:24:39 volumio volumio[969]: info: Volumio called home Jul 16 15:24:40 volumio mpd[1289]: Jul 16 15:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 16 15:24:40 volumio systemd[1]: Started Music Player Daemon. Jul 16 15:24:40 volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:40 volumio sudo[1256]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:40 volumio volumio[969]: info: Completed starting Core Plugins Jul 16 15:24:40 volumio volumio[969]: info: ------------------------------------------- Jul 16 15:24:40 volumio volumio[969]: info: ----- MyVolumio plugins startup ---- Jul 16 15:24:40 volumio volumio[969]: info: ------------------------------------------- Jul 16 15:24:40 volumio volumio[969]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 16 15:24:40 volumio volumio[969]: error: MPD error: The expression evaluated to a falsy value: Jul 16 15:24:40 volumio volumio[969]: assert.ok(self.idling) Jul 16 15:24:40 volumio volumio[969]: error: The expression evaluated to a falsy value: Jul 16 15:24:40 volumio volumio[969]: assert.ok(self.idling) Jul 16 15:24:40 volumio volumio[969]: info: MPD running with PID1289 Jul 16 15:24:40 volumio volumio[969]: ,establishing connection Jul 16 15:24:40 volumio volumio[969]: error: MPD error: The expression evaluated to a falsy value: Jul 16 15:24:40 volumio volumio[969]: assert.ok(self.idling) Jul 16 15:24:40 volumio volumio[969]: error: The expression evaluated to a falsy value: Jul 16 15:24:40 volumio volumio[969]: assert.ok(self.idling) Jul 16 15:24:40 volumio volumio[969]: error: updateQueue error: null Jul 16 15:24:41 volumio sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 16 15:24:41 volumio sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:41 volumio sudo[1333]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:41 volumio sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 16 15:24:41 volumio sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:41 volumio sudo[1335]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:41 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 16 15:24:41 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:41 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 16 15:24:41 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jul 16 15:24:41 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:41 volumio volumio[969]: info: Upmpdcli Daemon Started Jul 16 15:24:41 volumio volumio[1341]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 16 15:24:43 volumio sudo[1358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=SA6400,password=aA123456,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.62/music /mnt/NAS/S\'A6400 Jul 16 15:24:43 volumio sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:43 volumio kernel: CIFS: Attempting to mount //192.168.3.62/music Jul 16 15:24:43 volumio sh[500]: timed out Jul 16 15:24:43 volumio dhcpcd[537]: timed out Jul 16 15:24:43 volumio sh[500]: dhcpcd exited Jul 16 15:24:43 volumio dhcpcd[537]: dhcpcd exited Jul 16 15:24:43 volumio sh[500]: ifup: failed to bring up eth0 Jul 16 15:24:43 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 16 15:24:43 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 16 15:24:45 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jul 16 15:24:46 volumio volumio[969]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 16 15:24:47 volumio volumio[1341]: ..........................................++++ Jul 16 15:24:49 volumio sudo[1358]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:49 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 16 15:24:49 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 16 15:24:49 volumio volumio[969]: info: Cannot mount NAS S'A6400 at system boot, trial number 2 ,retrying in 5 seconds Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 16 15:24:53 volumio volumio[969]: info: Adding plugin bluetooth to MyMusic Plugins Jul 16 15:24:53 volumio volumio[969]: info: Adding plugin multiroom to MyMusic Plugins Jul 16 15:24:53 volumio volumio[969]: info: Adding plugin metavolumio to MyMusic Plugins Jul 16 15:24:53 volumio volumio[969]: info: Adding plugin cd_controller to MyMusic Plugins Jul 16 15:24:53 volumio volumio[969]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 16 15:24:53 volumio volumio[969]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 16 15:24:53 volumio volumio[969]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 16 15:24:53 volumio volumio[969]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 16 15:24:54 volumio volumio[969]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 16 15:24:54 volumio volumio[969]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 16 15:24:54 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:24:54 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:24:54 volumio volumio[969]: info: Starting MyVolumio Remote Streaming Endpoints Jul 16 15:24:54 volumio volumio[969]: info: MyVolumio login type: Token Jul 16 15:24:54 volumio volumio[969]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 16 15:24:54 volumio volumio[969]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 16 15:24:56 volumio volumio[969]: info: Starting Streaming Service Transparent Proxy Jul 16 15:24:56 volumio volumio[969]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 16 15:24:56 volumio volumio[969]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 16 15:24:56 volumio volumio[969]: info: Streaming services startup Jul 16 15:24:56 volumio volumio[969]: info: Starting Streaming Daemon Jul 16 15:24:56 volumio sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 16 15:24:56 volumio sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:56 volumio volumio[969]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 16 15:24:56 volumio sudo[1387]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:56 volumio sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=SA6400,password=aA123456,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.62/music /mnt/NAS/S\'A6400 Jul 16 15:24:56 volumio sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:56 volumio volumio[969]: error: Cannot start Volumio Streaming Daemon Jul 16 15:24:56 volumio volumio[969]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 16 15:24:56 volumio volumio[969]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 16 15:24:56 volumio kernel: CIFS: Attempting to mount //192.168.3.62/music Jul 16 15:24:56 volumio volumio[969]: error: MyVolumio Custom Token format not valid, refreshing it Jul 16 15:24:57 volumio volumio[969]: STREAMING PROXY: Starting server on port 3245 Jul 16 15:24:57 volumio volumio[969]: Node JS runtime: 14 Jul 16 15:24:57 volumio volumio[969]: info: MyVolumio login type: Token Jul 16 15:24:58 volumio volumio[969]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 16 15:24:59 volumio volumio[969]: info: MyVolumio token set successfully Jul 16 15:24:59 volumio volumio[969]: info: MYVOLUMIO: Adding device Jul 16 15:24:59 volumio volumio[969]: info: MYVOLUMIO: Evaluating Server Jul 16 15:24:59 volumio volumio[969]: info: MyVolumio status changed Jul 16 15:24:59 volumio volumio[969]: info: Streaming services startup Jul 16 15:24:59 volumio volumio[969]: info: Starting Streaming Daemon Jul 16 15:24:59 volumio volumio[969]: info: Removing browser output: myVolumio user plan is not superstar Jul 16 15:24:59 volumio volumio[969]: info: Removing audio output: Jul 16 15:24:59 volumio volumio[969]: info: Stoppping Tunnel 1 Jul 16 15:24:59 volumio sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 16 15:24:59 volumio sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:59 volumio sudo[1402]: pam_unix(sudo:session): session closed for user root Jul 16 15:24:59 volumio sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 16 15:24:59 volumio sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:24:59 volumio sudo[1405]: pam_unix(sudo:session): session closed for user root Jul 16 15:25:00 volumio systemd[1]: systemd-timedated.service: Succeeded. Jul 16 15:25:00 volumio volumio[969]: info: Remote SSH Stopped Jul 16 15:25:00 volumio volumio[969]: error: Cannot start Volumio Streaming Daemon Jul 16 15:25:00 volumio volumio[969]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 16 15:25:00 volumio volumio[969]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 16 15:25:00 volumio volumio[969]: info: Setting Geolocation for MyVolumio to as1 Jul 16 15:25:00 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:00 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:00 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:01 volumio volumio[969]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 16 15:25:01 volumio volumio[969]: info: Updating MyVolumio device info Jul 16 15:25:01 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:01 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:01 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:01 volumio volumio[969]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 16 15:25:02 volumio sudo[1395]: pam_unix(sudo:session): session closed for user root Jul 16 15:25:02 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 16 15:25:02 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 16 15:25:02 volumio volumio[969]: info: Cannot mount NAS S'A6400 at system boot, trial number 3 ,retrying in 5 seconds Jul 16 15:25:05 volumio volumio[969]: info: MYVOLUMIO: Adding device Jul 16 15:25:05 volumio volumio[969]: info: MYVOLUMIO: Evaluating Server Jul 16 15:25:05 volumio volumio[969]: info: Setting Geolocation for MyVolumio to as1 Jul 16 15:25:05 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:05 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:05 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:06 volumio volumio[969]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 16 15:25:07 volumio volumio[969]: info: Updating MyVolumio device info Jul 16 15:25:07 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:07 volumio sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=SA6400,password=aA123456,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.62/music /mnt/NAS/S\'A6400 Jul 16 15:25:07 volumio sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:25:07 volumio volumio[969]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 16 15:25:07 volumio kernel: CIFS: Attempting to mount //192.168.3.62/music Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 16 15:25:08 volumio volumio[969]: info: Discovery: Getting this device information Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:08 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 16 15:25:08 volumio volumio[969]: verbose: New Socket.io Connection to 192.168.3.58:3000 from 192.168.3.12 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:08 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 16 15:25:08 volumio volumio-remote-updater[568]: No test mode Jul 16 15:25:08 volumio volumio-remote-updater[568]: No alpha test mode Jul 16 15:25:08 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 16 15:25:09 volumio volumio[969]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Disable UI controls during DLNA playback
    • \n
  • Fix for Cast functionality halting due to memory filling up
    • \n
  • Fix for XML parsing on web-radio search
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Add link for downloading CORRD in the side-menu
    • \n
\n","title":"Update v3.819","updateavailable":true} Jul 16 15:25:09 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 16 15:25:09 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 16 15:25:09 volumio volumio[969]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 16 15:25:09 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:09 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:12 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:12 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:13 volumio volumio[969]: verbose: New Socket.io Connection to 192.168.3.58:3000 from 192.168.3.12 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jul 16 15:25:13 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:13 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:13 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 16 15:25:13 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 16 15:25:13 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:13 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:13 volumio sudo[1457]: pam_unix(sudo:session): session closed for user root Jul 16 15:25:13 volumio volumio[969]: info: Cannot mount NAS S'A6400 at system boot, trial number 4 ,retrying in 5 seconds Jul 16 15:25:13 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 16 15:25:13 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 16 15:25:14 volumio sudo[1517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 16 15:25:14 volumio sudo[1517]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:25:14 volumio sudo[1517]: pam_unix(sudo:session): session closed for user root Jul 16 15:25:14 volumio sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 16 15:25:14 volumio sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:25:14 volumio sudo[1519]: pam_unix(sudo:session): session closed for user root Jul 16 15:25:14 volumio volumio[969]: verbose: New Socket.io Connection to 192.168.3.58 from 192.168.3.12 UA: Mozilla/5.0 (Linux; Android 14; TB372FC Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.63 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::volumioGetVisibleSources Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:14 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 16 15:25:14 volumio sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 16 15:25:14 volumio sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:25:14 volumio sudo[1523]: pam_unix(sudo:session): session closed for user root Jul 16 15:25:14 volumio sudo[1525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 16 15:25:14 volumio sudo[1525]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:25:14 volumio sudo[1525]: pam_unix(sudo:session): session closed for user root Jul 16 15:25:14 volumio volumio[969]: verbose: New Socket.io Connection to 192.168.3.58 from 192.168.3.12 UA: Mozilla/5.0 (Linux; Android 14; TB372FC Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.63 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::volumioGetBrowseSources Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:14 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 16 15:25:14 volumio volumio[969]: info: Listing playlists Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 16 15:25:14 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 16 15:25:16 volumio volumio[969]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 16 15:25:16 volumio volumio[969]: info: Completed starting MyVolumio Plugin Jul 16 15:25:16 volumio volumio[969]: [Metrics] CommandRouter: 48s 873.60ms Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::volumiosetStartupVolume Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::Close All Modals sent Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::Close All Modals sent Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 16 15:25:16 volumio volumio[969]: info: Received Get System Info Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 16 15:25:16 volumio volumio[969]: info: Discovery: Getting this device information Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:16 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 16 15:25:16 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 16 15:25:17 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 16 15:25:17 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 16 15:25:17 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 16 15:25:17 volumio volumio[1341]: ...............................................................................................................................................................................................................................++++ Jul 16 15:25:17 volumio volumio[1341]: e is 65537 (0x010001) Jul 16 15:25:17 volumio volumio[1341]: writing RSA key Jul 16 15:25:18 volumio volumio[969]: info: CoreCommandRouter::volumioGetVisibleSources Jul 16 15:25:18 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 16 15:25:18 volumio volumio[969]: info: CoreCommandRouter::volumioGetQueue Jul 16 15:25:18 volumio volumio[969]: info: CoreStateMachine::getQueue Jul 16 15:25:18 volumio volumio[969]: info: CorePlayQueue::getQueue Jul 16 15:25:19 volumio sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=SA6400,password=aA123456,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.62/music /mnt/NAS/S\'A6400 Jul 16 15:25:19 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 15:25:19 volumio kernel: CIFS: Attempting to mount //192.168.3.62/music Jul 16 15:25:19 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 16 15:25:19 volumio volumio[969]: info: Preload queue cleared Jul 16 15:25:20 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 16 15:25:20 volumio volumio[969]: info: Received Get System Info Jul 16 15:25:20 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 16 15:25:20 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 16 15:25:20 volumio volumio[969]: info: Discovery: Getting this device information Jul 16 15:25:20 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:20 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:20 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 16 15:25:21 volumio ntpd[747]: Soliciting pool server 209.58.185.100 Jul 16 15:25:21 volumio ntpd[747]: Soliciting pool server 162.159.200.123 Jul 16 15:25:22 volumio ntpd[747]: Soliciting pool server 162.159.200.1 Jul 16 15:25:22 volumio ntpd[747]: Soliciting pool server 162.159.200.1 Jul 16 15:25:22 volumio ntpd[747]: Soliciting pool server 103.214.22.128 Jul 16 15:25:23 volumio ntpd[747]: Soliciting pool server 2606:4700:f1::123 Jul 16 15:25:23 volumio volumio[969]: info: BOOT COMPLETED Jul 16 15:25:25 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 16 15:25:25 volumio volumio[969]: info: Preload queue cleared Jul 16 15:25:25 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Jul 16 15:25:25 volumio volumio[969]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jul 16 15:25:25 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 16 15:25:25 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 16 15:25:28 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 16 15:25:28 volumio volumio[969]: info: Preload queue cleared Jul 16 15:25:30 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 16 15:25:30 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 16 15:25:30 volumio volumio[969]: info: Discovery: Getting this device information Jul 16 15:25:30 volumio volumio[969]: info: CoreCommandRouter::volumioGetState Jul 16 15:25:30 volumio volumio[969]: info: CorePlayQueue::getTrack 0 Jul 16 15:25:30 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 16 15:25:31 volumio ntpd[747]: receive: Unexpected origin timestamp 0xec21d26a.3d724de7 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xec21d26b.88c583fa Jul 16 15:25:31 volumio ntpd[747]: receive: Unexpected origin timestamp 0xec21d26a.3d77cd4a does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xec21d26b.88da5778 Jul 16 15:25:32 volumio volumio[969]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 16 15:25:32 volumio volumio[969]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 15:25:32 volumio volumio[969]: TypeError: Cannot read property 'length' of undefined Jul 16 15:25:32 volumio volumio[969]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 16 15:25:32 volumio volumio[969]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 16 15:25:32 volumio volumio[969]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 16 15:25:32 volumio volumio[969]: at Parser.emit (events.js:315:20) Jul 16 15:25:32 volumio volumio[969]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 16 15:25:32 volumio volumio[969]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 16 15:25:32 volumio volumio[969]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 16 15:25:32 volumio volumio[969]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 16 15:25:32 volumio volumio[969]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 16 15:25:32 volumio volumio[969]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 16 15:25:32 volumio volumio[969]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 16 15:25:32 volumio volumio[969]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 16 15:25:32 volumio volumio[969]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 16 15:25:32 volumio volumio[969]: at IncomingMessage.emit (events.js:327:22) Jul 16 15:25:32 volumio volumio[969]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 16 15:25:32 volumio volumio[969]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 16 15:25:32 volumio volumio[969]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 15:25:33 volumio sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-16 15:24 Jul 16 15:25:33 volumio sudo[1617]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"