-- Logs begin at Fri 2025-01-17 16:16:39 EST, end at Fri 2025-01-17 19:17:03 EST. -- Jan 17 19:16:03 volumio systemd-timedated[990]: Changed local time to Fri Jan 17 19:16:03 2025 Jan 17 19:16:03 volumio sudo[988]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:03 volumio volumio-time-update[582]: volumio-time-update-util: System time updated successfully. Jan 17 19:16:03 volumio systemd[1]: Started Volumio Time Update Utility. Jan 17 19:16:03 volumio systemd[1]: Reached target Multi-User System. Jan 17 19:16:03 volumio systemd[1]: Reached target Graphical Interface. Jan 17 19:16:03 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 17 19:16:03 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 17 19:16:03 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 17 19:16:03 volumio systemd[1]: Startup finished in 13.639s (kernel) + 16.450s (userspace) = 30.089s. Jan 17 19:16:04 volumio volumio[888]: info: Loading plugin "network"... Jan 17 19:16:04 volumio volumio[888]: info: Refreshing Cached IP Addresses Jan 17 19:16:04 volumio sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 19:16:04 volumio sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:04 volumio sudo[995]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:04 volumio sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 19:16:04 volumio sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:04 volumio volumio[888]: info: Loading plugin "services"... Jan 17 19:16:04 volumio sudo[997]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:04 volumio volumio[888]: info: Loading plugin "alsa_controller"... Jan 17 19:16:04 volumio sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 17 19:16:04 volumio sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:04 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 19:16:04 volumio volumio[888]: info: Loading plugin "wizard"... Jan 17 19:16:04 volumio volumio[888]: info: Loading plugin "networkfs"... Jan 17 19:16:04 volumio volumio[888]: info: Starting Udev Watcher for removable devices Jan 17 19:16:04 volumio sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.77/media/music /mnt/NAS/NAS4C4E03 Jan 17 19:16:04 volumio sudo[1023]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:04 volumio volumio[888]: info: Ignoring mount for partition: boot Jan 17 19:16:04 volumio volumio[888]: info: Ignoring mount for partition: volumio Jan 17 19:16:04 volumio volumio[888]: info: Ignoring mount for partition: volumio_data Jan 17 19:16:04 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 19:16:04 volumio volumio[888]: info: Loading plugin "volumio_command_line_client"... Jan 17 19:16:04 volumio volumio[888]: info: Loading plugin "upnp"... Jan 17 19:16:04 volumio volumio[888]: info: [1737159364613] Starting Upmpd Daemon Jan 17 19:16:04 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 19:16:04 volumio volumio[888]: info: Loading plugin "my_music"... Jan 17 19:16:04 volumio volumio[888]: info: Loading plugin "mpd"... Jan 17 19:16:04 volumio kernel: Key type cifs.spnego registered Jan 17 19:16:04 volumio kernel: Key type cifs.idmap registered Jan 17 19:16:04 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. Jan 17 19:16:04 volumio kernel: CIFS: Attempting to mount //192.168.1.77/media/music Jan 17 19:16:04 volumio volumio-remote-updater[607]: [2025-01-17 19:16:04] [connect] Successful connection Jan 17 19:16:05 volumio sudo[1006]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:05 volumio volumio[888]: info: Loading plugin "upnp_browser"... Jan 17 19:16:05 volumio volumio[888]: info: Loading plugin "alarm-clock"... Jan 17 19:16:05 volumio volumio[888]: info: Loading plugin "airplay_emulation"... Jan 17 19:16:05 volumio volumio[888]: info: Starting Shairport Sync Jan 17 19:16:05 volumio volumio[888]: info: Loading plugin "last_100"... Jan 17 19:16:05 volumio volumio[888]: info: Loading plugin "webradio"... Jan 17 19:16:06 volumio volumio[888]: info: Loading plugin "i2s_dacs"... Jan 17 19:16:06 volumio volumio[888]: info: Loading plugin "volumiodiscovery"... Jan 17 19:16:06 volumio volumio[888]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 17 19:16:06 volumio volumio[888]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 19:16:06 volumio volumio[888]: *** WARNING *** For more information see Jan 17 19:16:06 volumio volumio[888]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 17 19:16:06 volumio node[888]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 17 19:16:06 volumio volumio[888]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 19:16:06 volumio volumio[888]: *** WARNING *** For more information see Jan 17 19:16:06 volumio node[888]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 19:16:06 volumio node[888]: *** WARNING *** For more information see Jan 17 19:16:06 volumio node[888]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 17 19:16:06 volumio node[888]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 19:16:06 volumio node[888]: *** WARNING *** For more information see Jan 17 19:16:06 volumio volumio[888]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 17 19:16:06 volumio volumio[888]: info: Discovery: Started advertising with name: Volumio Jan 17 19:16:06 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 19:16:06 volumio volumio[888]: info: Loading plugin "soundcloud"... Jan 17 19:16:06 volumio volumio[888]: info: Loading plugin "ytmusic"... Jan 17 19:16:08 volumio volumio[888]: info: Loading plugin "outputs"... Jan 17 19:16:08 volumio volumio[888]: info: Loading plugin "albumart"... Jan 17 19:16:08 volumio volumio[888]: info: Plugin example_plugin is not enabled Jan 17 19:16:08 volumio volumio[888]: info: Loading plugin "inputs"... Jan 17 19:16:08 volumio volumio[888]: info: Loading plugin "updater_comm"... Jan 17 19:16:08 volumio volumio[888]: info: Plugin mpdemulation is not enabled Jan 17 19:16:08 volumio volumio[888]: info: Loading plugin "rest_api"... Jan 17 19:16:08 volumio volumio[888]: info: Loading plugin "websocket"... Jan 17 19:16:08 volumio volumio[888]: info: Starting Socket.io Server version 2.3.0 Jan 17 19:16:08 volumio volumio[888]: info: Plugin fusiondsp is not enabled Jan 17 19:16:08 volumio volumio[888]: info: Loading i18n strings for locale en Jan 17 19:16:08 volumio volumio[888]: Updating browse sources language Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:16:09 volumio volumio[888]: Forking 3 albumart workers Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::initPlayerControls Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:16:09 volumio volumio[888]: Express server listening on port 3000 Jan 17 19:16:09 volumio volumio[888]: [Metrics] WebUI: 9s 457.61ms Jan 17 19:16:09 volumio volumio[888]: info: CoreStateMachine::resetVolumioState Jan 17 19:16:09 volumio volumio[888]: info: CoreStateMachine::getcurrentVolume Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::volumioRetrievevolume Jan 17 19:16:09 volumio volumio[888]: info: Volumio Network Manager: Network status updated: 1 Jan 17 19:16:09 volumio volumio-remote-updater[607]: [2025-01-17 19:16:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737159364 101 Jan 17 19:16:09 volumio volumio[888]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:09 volumio volumio[888]: info: Reloading queue from file Jan 17 19:16:09 volumio volumio[888]: info: CoreStateMachine::setRepeat true single undefined Jan 17 19:16:09 volumio volumio[888]: info: CoreStateMachine::pushState Jan 17 19:16:09 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Jan 17 19:16:09 volumio volumio[888]: info: CoreStateMachine::setRandom null Jan 17 19:16:09 volumio volumio[888]: info: CoreStateMachine::pushState Jan 17 19:16:09 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Jan 17 19:16:09 volumio volumio[888]: info: Setting Device type: Raspberry PI Jan 17 19:16:09 volumio volumio[888]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 17 19:16:09 volumio volumio[888]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jan 17 19:16:09 volumio volumio[888]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 17 19:16:09 volumio volumio[888]: info: VolumeController:: Volume=0 Mute =false Jan 17 19:16:09 volumio volumio[888]: info: CoreStateMachine::pushState Jan 17 19:16:09 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Jan 17 19:16:09 volumio volumio[888]: info: CoreStateMachine::updateTrackBlock Jan 17 19:16:09 volumio volumio[888]: info: CorePlayQueue::getTrackBlock Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::volumioRetrievevolume Jan 17 19:16:09 volumio volumio[888]: info: Discovery: adding 7ef883c3-d1fa-4e26-ace7-3ab2d574a0db Jan 17 19:16:09 volumio volumio[888]: info: Discovery: Found device Volumio Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:09 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:09 volumio volumio[888]: info: Discovery: this is already registered, 7ef883c3-d1fa-4e26-ace7-3ab2d574a0db Jan 17 19:16:09 volumio volumio[888]: info: Discovery: Found device Volumio Jan 17 19:16:09 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:09 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:10 volumio volumio[888]: info: VolumeController:: Volume=0 Mute =false Jan 17 19:16:10 volumio volumio[888]: info: CoreStateMachine::pushState Jan 17 19:16:10 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:10 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Jan 17 19:16:10 volumio volumio[888]: Starting albumart workers Jan 17 19:16:10 volumio volumio[888]: Starting albumart workers Jan 17 19:16:10 volumio volumio[888]: Starting albumart workers Jan 17 19:16:11 volumio sudo[1023]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:11 volumio volumio[888]: info: Cannot mount NAS NAS4C4E03 at system boot, trial number 1 ,retrying in 5 seconds Jan 17 19:16:11 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 17 19:16:11 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 17 19:16:11 volumio volumio[888]: info: Completed loading Core Plugins Jan 17 19:16:11 volumio volumio[888]: info: Preparing to generate the ALSA configuration file Jan 17 19:16:11 volumio volumio[888]: info: Asound.conf file unchanged, so no further update is needed Jan 17 19:16:11 volumio volumio[888]: info: Output device has changed, restarting MPD Jan 17 19:16:11 volumio volumio[888]: info: Output device has changed, restarting Shairport Sync Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:11 volumio sudo[1109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 17 19:16:11 volumio sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:11 volumio sudo[1109]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:11 volumio sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 17 19:16:11 volumio sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:11 volumio systemd[1]: Stopping Music Player Daemon... Jan 17 19:16:11 volumio volumio[888]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 17 19:16:11 volumio volumio[888]: info: ___________ START PLUGINS ___________ Jan 17 19:16:11 volumio volumio[888]: info: ControllerMpd::onStart: Initializing MPD Jan 17 19:16:11 volumio volumio[888]: info: Creating MPD Configuration file Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 19:16:11 volumio sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 17 19:16:11 volumio volumio[888]: info: [1737159371224] CoreMusicLibrary::Adding element Media Servers Jan 17 19:16:11 volumio sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:16:11 volumio sudo[1118]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:11 volumio sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 17 19:16:11 volumio sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:11 volumio systemd[1]: mpd.service: Succeeded. Jan 17 19:16:11 volumio systemd[1]: Stopped Music Player Daemon. Jan 17 19:16:11 volumio volumio[888]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 19:16:11 volumio volumio[888]: info: [1737159371388] CoreMusicLibrary::Adding element Last_100 Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 19:16:11 volumio volumio[888]: info: [1737159371392] CoreMusicLibrary::Adding element Webradio Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 19:16:11 volumio volumio[888]: info: Initializing BBC Radios Jan 17 19:16:11 volumio systemd[1]: Starting Music Player Daemon... Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 19:16:11 volumio volumio[888]: info: [1737159371475] CoreMusicLibrary::Adding element SoundCloud Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:16:11 volumio volumio[888]: Cannot find translation for source SoundCloud Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 19:16:11 volumio volumio[888]: info: [1737159371486] CoreMusicLibrary::Adding element YouTube Music Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:16:11 volumio volumio[888]: Cannot find translation for source SoundCloud Jan 17 19:16:11 volumio volumio[888]: Cannot find translation for source YouTube Music Jan 17 19:16:11 volumio volumio[888]: info: Volumio Calling Home Jan 17 19:16:11 volumio sudo[1129]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 19:16:11 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:11 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:11 volumio volumio[888]: info: MPD Permissions set Jan 17 19:16:11 volumio volumio[888]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:12 volumio volumio[888]: info: Volumio called home Jan 17 19:16:12 volumio volumio[888]: info: Starting Shairport Sync Jan 17 19:16:12 volumio volumio[888]: info: Starting Shairport Sync Jan 17 19:16:12 volumio volumio[888]: info: Starting Shairport Sync Jan 17 19:16:12 volumio sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 17 19:16:12 volumio sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:12 volumio sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 17 19:16:12 volumio sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:12 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:12 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:12 volumio sudo[1159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 17 19:16:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 17 19:16:12 volumio sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:12 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 17 19:16:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 17 19:16:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 17 19:16:12 volumio sudo[1156]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:12 volumio sudo[1154]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:12 volumio volumio[888]: info: Shairport-Sync Started Jan 17 19:16:12 volumio volumio[888]: Error adding Membership: Error: addMembership EINVAL Jan 17 19:16:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 17 19:16:12 volumio volumio[888]: info: Shairport-Sync Started Jan 17 19:16:12 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 17 19:16:12 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 17 19:16:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 17 19:16:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 17 19:16:12 volumio sudo[1159]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:12 volumio volumio[888]: info: Shairport-Sync Started Jan 17 19:16:13 volumio mpd[1144]: Jan 17 19:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 19:16:13 volumio systemd[1]: Started Music Player Daemon. Jan 17 19:16:13 volumio sudo[1111]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:13 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:13 volumio volumio[888]: info: Completed starting Core Plugins Jan 17 19:16:13 volumio volumio[888]: info: ------------------------------------------- Jan 17 19:16:13 volumio volumio[888]: info: ----- MyVolumio plugins startup ---- Jan 17 19:16:13 volumio volumio[888]: info: ------------------------------------------- Jan 17 19:16:13 volumio volumio[888]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 17 19:16:13 volumio volumio[888]: error: MPD error: The expression evaluated to a falsy value: Jan 17 19:16:13 volumio volumio[888]: assert.ok(self.idling) Jan 17 19:16:13 volumio volumio[888]: error: The expression evaluated to a falsy value: Jan 17 19:16:13 volumio volumio[888]: assert.ok(self.idling) Jan 17 19:16:13 volumio volumio[888]: error: updateQueue error: null Jan 17 19:16:13 volumio volumio[888]: info: MPD running with PID1144 Jan 17 19:16:13 volumio volumio[888]: ,establishing connection Jan 17 19:16:13 volumio volumio[888]: error: updateQueue error: null Jan 17 19:16:14 volumio sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 19:16:14 volumio sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:14 volumio sudo[1186]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:14 volumio sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 19:16:14 volumio sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:14 volumio sudo[1188]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:14 volumio sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 17 19:16:14 volumio sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:14 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 17 19:16:14 volumio sudo[1192]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:14 volumio volumio[888]: info: Upmpdcli Daemon Started Jan 17 19:16:14 volumio volumio[1194]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 17 19:16:15 volumio volumio[888]: verbose: New Socket.io Connection to 192.168.1.175 from 192.168.1.113 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:15 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:15 volumio volumio[888]: info: Received Get System Info Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:16:15 volumio volumio[888]: info: Discovery: Getting this device information Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:15 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:15 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:15 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:15 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:16:15 volumio volumio[888]: info: CoreStateMachine::getQueue Jan 17 19:16:15 volumio volumio[888]: info: CorePlayQueue::getQueue Jan 17 19:16:15 volumio volumio[888]: info: Listing playlists Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 19:16:15 volumio volumio[888]: info: Received Get System Info Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:16:15 volumio volumio[888]: info: Discovery: Getting this device information Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:15 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:16:15 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:15 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:16 volumio sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.77/media/music /mnt/NAS/NAS4C4E03 Jan 17 19:16:16 volumio sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:16 volumio kernel: CIFS: Attempting to mount //192.168.1.77/media/music Jan 17 19:16:19 volumio volumio[888]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 17 19:16:19 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 17 19:16:21 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 19:16:21 volumio volumio[888]: info: Received Get System Info Jan 17 19:16:21 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:16:21 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:16:21 volumio volumio[888]: info: Discovery: Getting this device information Jan 17 19:16:21 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:21 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:21 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:16:22 volumio sudo[1211]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:22 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 17 19:16:22 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 17 19:16:23 volumio volumio[888]: Cannot compose Albumart path Jan 17 19:16:24 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jan 17 19:16:24 volumio volumio[888]: info: Cannot mount NAS NAS4C4E03 at system boot, trial number 2 ,retrying in 5 seconds Jan 17 19:16:24 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 17 19:16:24 volumio volumio[888]: info: Adding plugin bluetooth to MyMusic Plugins Jan 17 19:16:24 volumio volumio[888]: info: Adding plugin multiroom to MyMusic Plugins Jan 17 19:16:24 volumio volumio[888]: info: Adding plugin metavolumio to MyMusic Plugins Jan 17 19:16:24 volumio volumio[888]: info: Adding plugin cd_controller to MyMusic Plugins Jan 17 19:16:24 volumio volumio[888]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 17 19:16:24 volumio volumio[888]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 17 19:16:24 volumio volumio[888]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 17 19:16:26 volumio volumio[888]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 17 19:16:26 volumio volumio[888]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 17 19:16:26 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:26 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:26 volumio volumio[888]: info: Starting MyVolumio Remote Streaming Endpoints Jan 17 19:16:26 volumio volumio[888]: info: MyVolumio login type: Token Jan 17 19:16:26 volumio volumio[888]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 17 19:16:26 volumio volumio[888]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 17 19:16:27 volumio volumio[888]: info: Starting Streaming Service Transparent Proxy Jan 17 19:16:27 volumio volumio[888]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 17 19:16:27 volumio volumio[888]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 17 19:16:27 volumio volumio[888]: info: Streaming services startup Jan 17 19:16:27 volumio volumio[888]: info: Starting Streaming Daemon Jan 17 19:16:27 volumio sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 17 19:16:27 volumio sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:27 volumio volumio[888]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 17 19:16:28 volumio sudo[1243]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:28 volumio volumio[888]: error: Cannot start Volumio Streaming Daemon Jan 17 19:16:28 volumio volumio[888]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 17 19:16:28 volumio volumio[888]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 17 19:16:28 volumio volumio[888]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 17 19:16:28 volumio volumio[888]: info: MyVolumio token set successfully Jan 17 19:16:28 volumio volumio[888]: info: MYVOLUMIO: Adding device Jan 17 19:16:28 volumio volumio[888]: info: MYVOLUMIO: Evaluating Server Jan 17 19:16:29 volumio volumio[888]: info: MyVolumio status changed Jan 17 19:16:29 volumio volumio[888]: info: Streaming services startup Jan 17 19:16:29 volumio volumio[888]: info: Starting Streaming Daemon Jan 17 19:16:29 volumio volumio[888]: info: Removing browser output: myVolumio user plan is not superstar Jan 17 19:16:29 volumio volumio[888]: info: Removing audio output: Jan 17 19:16:29 volumio volumio[888]: info: Stoppping Tunnel 1 Jan 17 19:16:29 volumio sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 17 19:16:29 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:29 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 17 19:16:29 volumio volumio[888]: STREAMING PROXY: Starting server on port 3245 Jan 17 19:16:29 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:29 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:29 volumio volumio[888]: Node JS runtime: 14 Jan 17 19:16:29 volumio volumio[888]: error: Cannot start Volumio Streaming Daemon Jan 17 19:16:29 volumio volumio[888]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 17 19:16:29 volumio volumio[888]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 17 19:16:29 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:29 volumio volumio[888]: info: Remote SSH Stopped Jan 17 19:16:29 volumio volumio[888]: info: Setting Geolocation for MyVolumio to us1 Jan 17 19:16:29 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:29 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:29 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:29 volumio volumio[888]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 17 19:16:29 volumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.77/media/music /mnt/NAS/NAS4C4E03 Jan 17 19:16:29 volumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:29 volumio kernel: CIFS: Attempting to mount //192.168.1.77/media/music Jan 17 19:16:30 volumio volumio[888]: info: Updating MyVolumio device info Jan 17 19:16:30 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:30 volumio volumio[888]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 17 19:16:33 volumio systemd[1]: systemd-timedated.service: Succeeded. Jan 17 19:16:33 volumio volumio[888]: info: MYVOLUMIO: Adding device Jan 17 19:16:33 volumio volumio[888]: info: MYVOLUMIO: Evaluating Server Jan 17 19:16:34 volumio volumio[888]: info: Setting Geolocation for MyVolumio to us1 Jan 17 19:16:34 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:34 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:34 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:34 volumio volumio[888]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 17 19:16:35 volumio volumio[888]: info: Updating MyVolumio device info Jan 17 19:16:35 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:35 volumio sudo[1277]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:35 volumio volumio[888]: info: Cannot mount NAS NAS4C4E03 at system boot, trial number 3 ,retrying in 5 seconds Jan 17 19:16:35 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 17 19:16:35 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 17 19:16:36 volumio volumio[1194]: ................................................................................................................................................................++++ Jan 17 19:16:36 volumio volumio[888]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 17 19:16:40 volumio sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.77/media/music /mnt/NAS/NAS4C4E03 Jan 17 19:16:40 volumio sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:40 volumio kernel: CIFS: Attempting to mount //192.168.1.77/media/music Jan 17 19:16:41 volumio sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 19:16:41 volumio sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:41 volumio sudo[1321]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:41 volumio sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 19:16:41 volumio sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:41 volumio sudo[1323]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:41 volumio volumio[888]: verbose: New Socket.io Connection to 192.168.1.175 from 192.168.1.113 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 17 19:16:41 volumio volumio[888]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:41 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 19:16:41 volumio volumio-remote-updater[607]: No test mode Jan 17 19:16:41 volumio volumio-remote-updater[607]: No alpha test mode Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 17 19:16:41 volumio sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 19:16:41 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:41 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:41 volumio sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 19:16:41 volumio sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:41 volumio sudo[1332]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:41 volumio volumio[888]: verbose: New Socket.io Connection to 192.168.1.175 from 192.168.1.113 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 17 19:16:41 volumio volumio[888]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:41 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 19:16:41 volumio volumio[888]: info: Received Get System Info Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:16:41 volumio volumio[888]: info: Discovery: Getting this device information Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:41 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:41 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:41 volumio volumio[888]: info: Listing playlists Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 17 19:16:41 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 17 19:16:42 volumio volumio[1194]: ..........................................++++ Jan 17 19:16:42 volumio volumio[1194]: e is 65537 (0x010001) Jan 17 19:16:42 volumio volumio[1194]: writing RSA key Jan 17 19:16:43 volumio volumio[888]: info: VolumeController::SetAlsaVolume0 Jan 17 19:16:43 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:43 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:43 volumio volumio[888]: info: CoreStateMachine::pushState Jan 17 19:16:43 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:43 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:16:43 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 19:16:44 volumio volumio[888]: info: Received Get System Info Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:16:44 volumio volumio[888]: info: Discovery: Getting this device information Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:44 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 19:16:44 volumio volumio[888]: info: Received Get System Info Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:16:44 volumio volumio[888]: info: Discovery: Getting this device information Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:16:44 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:16:44 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:16:46 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:46 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:46 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 17 19:16:46 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 17 19:16:46 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 17 19:16:46 volumio volumio[888]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 19:16:46 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 19:16:47 volumio sudo[1316]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:47 volumio volumio[888]: info: Cannot mount NAS NAS4C4E03 at system boot, trial number 4 ,retrying in 5 seconds Jan 17 19:16:47 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 17 19:16:47 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 17 19:16:47 volumio volumio[888]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 17 19:16:47 volumio volumio[888]: info: Completed starting MyVolumio Plugin Jan 17 19:16:47 volumio volumio[888]: [Metrics] CommandRouter: 46s 882.21ms Jan 17 19:16:47 volumio volumio[888]: info: CoreCommandRouter::volumiosetStartupVolume Jan 17 19:16:47 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 19:16:47 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 19:16:48 volumio volumio[888]: info: CoreCommandRouter::Close All Modals sent Jan 17 19:16:48 volumio volumio[888]: info: CoreCommandRouter::Close All Modals sent Jan 17 19:16:49 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 17 19:16:49 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 17 19:16:49 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 17 19:16:52 volumio sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=admin1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.77/media/music /mnt/NAS/NAS4C4E03 Jan 17 19:16:52 volumio sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:16:52 volumio kernel: CIFS: Attempting to mount //192.168.1.77/media/music Jan 17 19:16:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 17 19:16:55 volumio volumio[888]: info: BOOT COMPLETED Jan 17 19:16:58 volumio sudo[1377]: pam_unix(sudo:session): session closed for user root Jan 17 19:16:58 volumio volumio[888]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 17 19:16:58 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 17 19:16:58 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 17 19:17:00 volumio ntpd[788]: Soliciting pool server 172.234.37.140 Jan 17 19:17:00 volumio ntpd[788]: Soliciting pool server 135.148.100.14 Jan 17 19:17:00 volumio volumio[888]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:17:00 volumio volumio[888]: info: CoreStateMachine::getQueue Jan 17 19:17:00 volumio volumio[888]: info: CorePlayQueue::getQueue Jan 17 19:17:01 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 17 19:17:01 volumio volumio[888]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 17 19:17:01 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 17 19:17:01 volumio volumio[888]: info: Received Get System Version Jan 17 19:17:01 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 19:17:01 volumio volumio[888]: info: Received Get System Info Jan 17 19:17:01 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:17:01 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:17:01 volumio volumio[888]: info: Discovery: Getting this device information Jan 17 19:17:01 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Jan 17 19:17:01 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Jan 17 19:17:01 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:17:01 volumio ntpd[788]: Soliciting pool server 108.61.73.243 Jan 17 19:17:01 volumio ntpd[788]: Soliciting pool server 66.118.231.14 Jan 17 19:17:01 volumio ntpd[788]: Soliciting pool server 162.159.200.1 Jan 17 19:17:02 volumio ntpd[788]: Soliciting pool server 23.142.248.8 Jan 17 19:17:02 volumio ntpd[788]: Soliciting pool server 45.83.234.123 Jan 17 19:17:02 volumio ntpd[788]: Soliciting pool server 129.146.193.200 Jan 17 19:17:02 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 17 19:17:02 volumio volumio[888]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 19:17:02 volumio volumio[888]: TypeError: Cannot read property 'length' of undefined Jan 17 19:17:02 volumio volumio[888]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 17 19:17:02 volumio volumio[888]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 17 19:17:02 volumio volumio[888]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 17 19:17:02 volumio volumio[888]: at Parser.emit (events.js:315:20) Jan 17 19:17:02 volumio volumio[888]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 17 19:17:02 volumio volumio[888]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 17 19:17:02 volumio volumio[888]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 17 19:17:02 volumio volumio[888]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 17 19:17:02 volumio volumio[888]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 17 19:17:02 volumio volumio[888]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 17 19:17:02 volumio volumio[888]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 17 19:17:02 volumio volumio[888]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 17 19:17:02 volumio volumio[888]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 17 19:17:02 volumio volumio[888]: at IncomingMessage.emit (events.js:327:22) Jan 17 19:17:02 volumio volumio[888]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 17 19:17:02 volumio volumio[888]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 17 19:17:02 volumio volumio[888]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 19:17:03 volumio ntpd[788]: Soliciting pool server 50.251.160.20 Jan 17 19:17:03 volumio ntpd[788]: Soliciting pool server 104.167.215.195 Jan 17 19:17:03 volumio sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-17 19:16 Jan 17 19:17:03 volumio sudo[1447]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"