-- Logs begin at Thu 2019-02-14 15:11:59 +05, end at Tue 2025-01-21 19:26:06 +05. -- Jan 21 19:25:06 volumio systemd-timedated[903]: Changed local time to Tue Jan 21 19:25:06 2025 Jan 21 19:25:06 volumio sudo[901]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:06 volumio volumio-time-update[552]: volumio-time-update-util: System time updated successfully. Jan 21 19:25:06 volumio systemd[1]: Starting Daily apt download activities... Jan 21 19:25:06 volumio systemd[1]: Started Volumio Time Update Utility. Jan 21 19:25:06 volumio systemd[1]: Reached target Multi-User System. Jan 21 19:25:06 volumio systemd[1]: Reached target Graphical Interface. Jan 21 19:25:06 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 21 19:25:06 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 21 19:25:06 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 21 19:25:07 volumio nmbd[690]: [2025/01/21 19:25:07.078708, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 21 19:25:07 volumio nmbd[690]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.40 for name WORKGROUP<1d>. Jan 21 19:25:07 volumio nmbd[690]: This response was from IP 192.168.1.1, reporting an IP address of 192.168.1.1. Jan 21 19:25:07 volumio volumio-remote-updater[585]: [2025-01-21 19:25:07] [connect] Successful connection Jan 21 19:25:07 volumio volumio[793]: info: Loading plugin "network"... Jan 21 19:25:07 volumio volumio[793]: info: Refreshing Cached IP Addresses Jan 21 19:25:07 volumio sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 19:25:07 volumio sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:07 volumio volumio[793]: info: Loading plugin "services"... Jan 21 19:25:07 volumio sudo[927]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:07 volumio volumio[793]: info: Loading plugin "alsa_controller"... Jan 21 19:25:07 volumio sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 19:25:07 volumio sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:07 volumio sudo[929]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:07 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 19:25:07 volumio volumio[793]: info: Loading plugin "wizard"... Jan 21 19:25:07 volumio volumio[793]: info: Loading plugin "networkfs"... Jan 21 19:25:08 volumio volumio[793]: info: Starting Udev Watcher for removable devices Jan 21 19:25:08 volumio sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.1/DATA /mnt/NAS/DATA Jan 21 19:25:08 volumio sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:08 volumio volumio[793]: info: Ignoring mount for partition: boot Jan 21 19:25:08 volumio volumio[793]: info: Ignoring mount for partition: volumio Jan 21 19:25:08 volumio volumio[793]: info: Ignoring mount for partition: volumio_data Jan 21 19:25:08 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 19:25:08 volumio volumio[793]: info: Loading plugin "volumio_command_line_client"... Jan 21 19:25:08 volumio volumio[793]: info: Loading plugin "upnp"... Jan 21 19:25:08 volumio volumio[793]: info: [1737469508227] Starting Upmpd Daemon Jan 21 19:25:08 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 19:25:08 volumio volumio[793]: info: Loading plugin "my_music"... Jan 21 19:25:08 volumio volumio[793]: info: Loading plugin "mpd"... Jan 21 19:25:08 volumio kernel: Key type cifs.spnego registered Jan 21 19:25:08 volumio kernel: Key type cifs.idmap registered Jan 21 19:25:08 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 21 19:25:08 volumio kernel: CIFS: Attempting to mount //192.168.1.1/DATA Jan 21 19:25:08 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jan 21 19:25:08 volumio sudo[951]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:09 volumio volumio[793]: info: Loading plugin "upnp_browser"... Jan 21 19:25:09 volumio volumio[793]: info: Loading plugin "alarm-clock"... Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "airplay_emulation"... Jan 21 19:25:10 volumio volumio[793]: info: Starting Shairport Sync Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "last_100"... Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "webradio"... Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "i2s_dacs"... Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "volumiodiscovery"... Jan 21 19:25:10 volumio volumio[793]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 19:25:10 volumio volumio[793]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 19:25:10 volumio volumio[793]: *** WARNING *** For more information see Jan 21 19:25:10 volumio volumio[793]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 19:25:10 volumio volumio[793]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 19:25:10 volumio node[793]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 21 19:25:10 volumio volumio[793]: *** WARNING *** For more information see Jan 21 19:25:10 volumio node[793]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 19:25:10 volumio node[793]: *** WARNING *** For more information see Jan 21 19:25:10 volumio node[793]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 21 19:25:10 volumio node[793]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 21 19:25:10 volumio node[793]: *** WARNING *** For more information see Jan 21 19:25:10 volumio volumio[793]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 21 19:25:10 volumio volumio[793]: info: Discovery: Started advertising with name: Volumio Jan 21 19:25:10 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 21 19:25:10 volumio volumio[793]: info: Plugin spop is not enabled Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "outputs"... Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "albumart"... Jan 21 19:25:10 volumio volumio[793]: info: Plugin example_plugin is not enabled Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "inputs"... Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "updater_comm"... Jan 21 19:25:10 volumio volumio[793]: info: Plugin mpdemulation is not enabled Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "rest_api"... Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "websocket"... Jan 21 19:25:10 volumio volumio[793]: info: Starting Socket.io Server version 2.3.0 Jan 21 19:25:10 volumio volumio[793]: info: Loading plugin "fusiondsp"... Jan 21 19:25:10 volumio volumio[793]: Forking 3 albumart workers Jan 21 19:25:11 volumio systemd[1]: apt-daily.service: Succeeded. Jan 21 19:25:11 volumio systemd[1]: Started Daily apt download activities. Jan 21 19:25:11 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jan 21 19:25:12 volumio volumio[793]: Starting albumart workers Jan 21 19:25:12 volumio volumio[793]: Starting albumart workers Jan 21 19:25:12 volumio volumio[793]: Starting albumart workers Jan 21 19:25:12 volumio volumio[793]: info: Applying required configuration parameters for plugin fusiondsp Jan 21 19:25:12 volumio volumio[793]: info: Loading i18n strings for locale en Jan 21 19:25:12 volumio volumio[793]: Updating browse sources language Jan 21 19:25:12 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::initPlayerControls Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 19:25:13 volumio volumio[793]: Express server listening on port 3000 Jan 21 19:25:13 volumio volumio[793]: [Metrics] WebUI: 11s 252.18ms Jan 21 19:25:13 volumio volumio[793]: info: CoreStateMachine::resetVolumioState Jan 21 19:25:13 volumio volumio[793]: info: CoreStateMachine::getcurrentVolume Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 19:25:13 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:13 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:13 volumio volumio[793]: info: Volumio Network Manager: Network status updated: 1 Jan 21 19:25:13 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 21 19:25:13 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jan 21 19:25:13 volumio systemd[1]: Startup finished in 25.064s (kernel) + 24.934s (userspace) = 49.999s. Jan 21 19:25:13 volumio volumio[793]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.74 UA: Mozilla/5.0 (Linux; Android 9; H4113 Build/50.2.A.3.77; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 21 19:25:13 volumio volumio[793]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.74 UA: Mozilla/5.0 (Linux; Android 9; H4113 Build/50.2.A.3.77; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: Reloading queue from file Jan 21 19:25:13 volumio volumio[793]: info: Setting Device type: Raspberry PI Jan 21 19:25:13 volumio volumio[793]: info: Completed loading Core Plugins Jan 21 19:25:13 volumio volumio[793]: info: Preparing to generate the ALSA configuration file Jan 21 19:25:13 volumio volumio[793]: info: CoreStateMachine::setRepeat false single undefined Jan 21 19:25:13 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:13 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:13 volumio volumio[793]: info: CoreStateMachine::setRandom false Jan 21 19:25:13 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:13 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:13 volumio volumio[793]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 21 19:25:13 volumio volumio[793]: info: Reading ALSA contributions from plugins. Jan 21 19:25:13 volumio volumio[793]: info: Asound.conf file unchanged, so no further update is needed Jan 21 19:25:13 volumio volumio[793]: info: Output device has changed, restarting MPD Jan 21 19:25:13 volumio volumio[793]: info: Output device has changed, restarting Shairport Sync Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:13 volumio sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 19:25:13 volumio sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:13 volumio sudo[1132]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:13 volumio sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 19:25:13 volumio sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:13 volumio systemd[1]: Stopping Music Player Daemon... Jan 21 19:25:13 volumio volumio[793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 19:25:13 volumio volumio[793]: info: ___________ START PLUGINS ___________ Jan 21 19:25:13 volumio volumio[793]: info: ControllerMpd::onStart: Initializing MPD Jan 21 19:25:13 volumio volumio[793]: info: Creating MPD Configuration file Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 19:25:13 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 19:25:13 volumio volumio[793]: info: [1737469513740] CoreMusicLibrary::Adding element Media Servers Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 19:25:13 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:13 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:13 volumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 19:25:13 volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:13 volumio systemd[1]: mpd.service: Succeeded. Jan 21 19:25:13 volumio systemd[1]: Stopped Music Player Daemon. Jan 21 19:25:13 volumio volumio[793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 19:25:13 volumio volumio[793]: info: [1737469513893] CoreMusicLibrary::Adding element Last_100 Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 21 19:25:13 volumio volumio[793]: info: [1737469513897] CoreMusicLibrary::Adding element Webradio Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 19:25:13 volumio volumio[793]: info: Initializing BBC Radios Jan 21 19:25:13 volumio systemd[1]: Starting Music Player Daemon... Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: Loading i18n strings for locale en Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 21 19:25:13 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:13 volumio volumio[793]: info: FusionDsp - mixtype--------------------- None Jan 21 19:25:13 volumio volumio[793]: info: Volumio Calling Home Jan 21 19:25:14 volumio sudo[1152]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 19:25:14 volumio sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:14 volumio sudo[1152]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:14 volumio volumio[793]: info: Preparing to generate the ALSA configuration file Jan 21 19:25:14 volumio volumio[793]: info: Discovery: adding f4728613-9924-4483-9f16-00f637a9ec6d Jan 21 19:25:14 volumio volumio[793]: info: Discovery: Found device Volumio Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:14 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:14 volumio volumio[793]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 21 19:25:14 volumio volumio[793]: info: Reading ALSA contributions from plugins. Jan 21 19:25:14 volumio volumio[793]: info: MPD Permissions set Jan 21 19:25:14 volumio volumio[793]: info: MPD Permissions set Jan 21 19:25:14 volumio volumio[793]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: Volumio called home Jan 21 19:25:14 volumio volumio[793]: info: Starting Shairport Sync Jan 21 19:25:14 volumio volumio[793]: info: Starting Shairport Sync Jan 21 19:25:14 volumio volumio[793]: info: Starting Shairport Sync Jan 21 19:25:14 volumio sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 19:25:14 volumio sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:14 volumio sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 19:25:14 volumio sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:14 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:14 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 19:25:14 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 19:25:14 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 21 19:25:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 19:25:14 volumio volumio[793]: info: Asound.conf file unchanged, so no further update is needed Jan 21 19:25:14 volumio volumio[793]: info: Output device has changed, restarting MPD Jan 21 19:25:14 volumio volumio[793]: info: Output device has changed, restarting Shairport Sync Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:14 volumio sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 21 19:25:14 volumio sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:14 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:14 volumio sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 21 19:25:14 volumio sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 19:25:14 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:14 volumio sudo[1177]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:14 volumio sudo[1179]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:14 volumio volumio[793]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 21 19:25:14 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 21 19:25:14 volumio systemd[1]: mpd.service: Succeeded. Jan 21 19:25:14 volumio systemd[1]: Stopped Music Player Daemon. Jan 21 19:25:14 volumio volumio[793]: info: MPD Permissions set Jan 21 19:25:14 volumio volumio[793]: info: Shairport-Sync Started Jan 21 19:25:14 volumio volumio[793]: Error adding Membership: Error: addMembership EINVAL Jan 21 19:25:14 volumio systemd[1]: Starting Music Player Daemon... Jan 21 19:25:14 volumio volumio[793]: info: Shairport-Sync Started Jan 21 19:25:14 volumio volumio[793]: info: Shairport-Sync Started Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:14 volumio volumio[793]: info: Starting Shairport Sync Jan 21 19:25:14 volumio sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 21 19:25:14 volumio sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 21 19:25:14 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 21 19:25:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 21 19:25:14 volumio sudo[1197]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 21 19:25:14 volumio sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 21 19:25:14 volumio sudo[1203]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:14 volumio sudo[1197]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:14 volumio volumio[793]: info: Shairport-Sync Started Jan 21 19:25:16 volumio volumio[793]: info: camilladsp spawned new process with pid 1217, instance 1, run: true Jan 21 19:25:16 volumio volumio[793]: info: camilladsp service started and running in background, instance 1 Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:16 volumio volumio[793]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Jan 21 19:25:16 volumio volumio[793]: info: FusionDsp loaded Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 19:25:16 volumio sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 21 19:25:16 volumio sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:16 volumio volumio[793]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 21 19:25:16 volumio volumio[793]: info: Adding Signal Path Element [object Object] Jan 21 19:25:16 volumio volumio[793]: info: Adding fusiondspeq DSP Signal Path Element Jan 21 19:25:16 volumio volumio[793]: info: FusionDsp - ---- installed callbackRead Jan 21 19:25:16 volumio volumio[793]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 21 19:25:16 volumio volumio[793]: error: FusionDsp - WebSocket error: [object Object] Jan 21 19:25:16 volumio volumio[793]: 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 21 19:25:16 volumio systemd[1]: Started FusionDsp Daemon. Jan 21 19:25:16 volumio sudo[1221]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:16 volumio mpd[1207]: Jan 21 19:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 21 19:25:16 volumio systemd[1]: Started Music Player Daemon. Jan 21 19:25:16 volumio sudo[1189]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:16 volumio sudo[1134]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:16 volumio sudo[1143]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:16 volumio volumio[793]: info: Completed starting Core Plugins Jan 21 19:25:16 volumio volumio[793]: info: ------------------------------------------- Jan 21 19:25:16 volumio volumio[793]: info: ----- MyVolumio plugins startup ---- Jan 21 19:25:16 volumio volumio[793]: info: ------------------------------------------- Jan 21 19:25:16 volumio volumio[793]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 21 19:25:16 volumio volumio[793]: error: MPD error: The expression evaluated to a falsy value: Jan 21 19:25:16 volumio volumio[793]: assert.ok(self.idling) Jan 21 19:25:16 volumio volumio[793]: error: The expression evaluated to a falsy value: Jan 21 19:25:16 volumio volumio[793]: assert.ok(self.idling) Jan 21 19:25:16 volumio volumio[793]: info: MPD running with PID1207 Jan 21 19:25:16 volumio volumio[793]: ,establishing connection Jan 21 19:25:16 volumio volumio[793]: error: MPD error: The expression evaluated to a falsy value: Jan 21 19:25:16 volumio volumio[793]: assert.ok(self.idling) Jan 21 19:25:16 volumio volumio[793]: error: The expression evaluated to a falsy value: Jan 21 19:25:16 volumio volumio[793]: assert.ok(self.idling) Jan 21 19:25:16 volumio volumio[793]: error: MPD error: The expression evaluated to a falsy value: Jan 21 19:25:16 volumio volumio[793]: assert.ok(self.idling) Jan 21 19:25:16 volumio volumio[793]: error: The expression evaluated to a falsy value: Jan 21 19:25:16 volumio volumio[793]: assert.ok(self.idling) Jan 21 19:25:16 volumio volumio[793]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.74 UA: Mozilla/5.0 (Linux; Android 9; H4113 Build/50.2.A.3.77; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/132.0.6834.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 21 19:25:16 volumio volumio[793]: error: updateQueue error: null Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:16 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 19:25:16 volumio volumio[793]: info: Received Get System Info Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 19:25:16 volumio volumio[793]: info: Discovery: Getting this device information Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:16 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:16 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:16 volumio volumio[793]: info: Listing playlists Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::volumioGetQueue Jan 21 19:25:16 volumio volumio[793]: info: CoreStateMachine::getQueue Jan 21 19:25:16 volumio volumio[793]: info: CorePlayQueue::getQueue Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 19:25:16 volumio volumio[793]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 19:25:18 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 19:25:18 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:18 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:18 volumio sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 19:25:18 volumio sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:18 volumio sudo[1251]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:18 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 21 19:25:18 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:18 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 21 19:25:18 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:18 volumio volumio[793]: info: Upmpdcli Daemon Started Jan 21 19:25:18 volumio volumio[1257]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 21 19:25:21 volumio volumio[793]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 21 19:25:21 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 21 19:25:22 volumio volumio-remote-updater[585]: [2025-01-21 19:25:22] [connect] Successful connection Jan 21 19:25:22 volumio volumio-remote-updater[585]: [2025-01-21 19:25:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737469522 101 Jan 21 19:25:22 volumio volumio[793]: 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: 6 Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 21 19:25:25 volumio volumio[793]: info: Adding plugin bluetooth to MyMusic Plugins Jan 21 19:25:25 volumio volumio[793]: info: Adding plugin multiroom to MyMusic Plugins Jan 21 19:25:25 volumio volumio[793]: info: Adding plugin metavolumio to MyMusic Plugins Jan 21 19:25:25 volumio volumio[793]: info: Adding plugin cd_controller to MyMusic Plugins Jan 21 19:25:25 volumio volumio[793]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 21 19:25:25 volumio volumio[793]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 21 19:25:25 volumio volumio[793]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 21 19:25:26 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jan 21 19:25:27 volumio volumio[793]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 21 19:25:27 volumio volumio[793]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 21 19:25:27 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:27 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:27 volumio volumio[793]: info: Starting MyVolumio Remote Streaming Endpoints Jan 21 19:25:27 volumio volumio[793]: info: MyVolumio login type: Token Jan 21 19:25:27 volumio volumio[793]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 21 19:25:27 volumio volumio[793]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 21 19:25:29 volumio volumio[793]: info: Starting Streaming Service Transparent Proxy Jan 21 19:25:29 volumio volumio[793]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 21 19:25:29 volumio volumio[793]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 21 19:25:29 volumio volumio[793]: info: Streaming services startup Jan 21 19:25:29 volumio volumio[793]: info: Starting Streaming Daemon Jan 21 19:25:29 volumio sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 21 19:25:29 volumio sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:29 volumio volumio[793]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 21 19:25:29 volumio sudo[1301]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:29 volumio volumio[793]: error: Cannot start Volumio Streaming Daemon Jan 21 19:25:29 volumio volumio[793]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 21 19:25:29 volumio volumio[793]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 21 19:25:29 volumio volumio[793]: error: MyVolumio Custom Token format not valid, refreshing it Jan 21 19:25:30 volumio volumio[793]: STREAMING PROXY: Starting server on port 3245 Jan 21 19:25:30 volumio volumio[793]: Node JS runtime: 14 Jan 21 19:25:30 volumio sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 19:25:30 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:30 volumio sudo[1309]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:30 volumio sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 19:25:30 volumio sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:30 volumio sudo[1311]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:30 volumio volumio[793]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.52 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 21 19:25:31 volumio sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 19:25:31 volumio sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:31 volumio sudo[1318]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:31 volumio sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 19:25:31 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:31 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:31 volumio volumio[793]: info: MyVolumio login type: Token Jan 21 19:25:31 volumio volumio[793]: verbose: New Socket.io Connection to 192.168.1.40 from 192.168.1.52 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:31 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 19:25:31 volumio volumio[793]: info: Received Get System Info Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 19:25:31 volumio volumio[793]: info: Discovery: Getting this device information Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:31 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:31 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:31 volumio volumio[793]: info: Listing playlists Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 21 19:25:31 volumio volumio[793]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 19:25:32 volumio volumio[793]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 21 19:25:32 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:32 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:33 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 19:25:33 volumio volumio[793]: info: Received Get System Info Jan 21 19:25:33 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 19:25:33 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 19:25:33 volumio volumio[793]: info: Discovery: Getting this device information Jan 21 19:25:33 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:33 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:33 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 19:25:33 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 21 19:25:33 volumio volumio[1257]: .............................................................................................................++++ Jan 21 19:25:33 volumio volumio[793]: info: MyVolumio token set successfully Jan 21 19:25:33 volumio volumio[793]: info: MYVOLUMIO: Adding device Jan 21 19:25:33 volumio volumio[793]: info: MYVOLUMIO: Evaluating Server Jan 21 19:25:34 volumio volumio[793]: info: MyVolumio status changed Jan 21 19:25:34 volumio volumio[793]: info: Streaming services startup Jan 21 19:25:34 volumio volumio[793]: info: Starting Streaming Daemon Jan 21 19:25:34 volumio volumio[793]: info: Removing browser output: myVolumio user plan is not superstar Jan 21 19:25:34 volumio volumio[793]: info: Removing audio output: Jan 21 19:25:34 volumio volumio[793]: info: Stoppping Tunnel 1 Jan 21 19:25:34 volumio sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 21 19:25:34 volumio sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:34 volumio sudo[1341]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:34 volumio sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 21 19:25:34 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 19:25:34 volumio volumio[793]: error: Cannot start Volumio Streaming Daemon Jan 21 19:25:34 volumio volumio[793]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 21 19:25:34 volumio volumio[793]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 21 19:25:34 volumio sudo[1344]: pam_unix(sudo:session): session closed for user root Jan 21 19:25:34 volumio volumio[793]: info: Remote SSH Stopped Jan 21 19:25:34 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 19:25:34 volumio volumio[793]: info: Received Get System Info Jan 21 19:25:34 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 19:25:34 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 19:25:34 volumio volumio[793]: info: Discovery: Getting this device information Jan 21 19:25:34 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:34 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:34 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 19:25:34 volumio volumio[793]: info: Setting Geolocation for MyVolumio to eu4 Jan 21 19:25:34 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:35 volumio volumio[793]: info: Updating MyVolumio device info Jan 21 19:25:35 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:35 volumio volumio[793]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 21 19:25:36 volumio systemd[1]: systemd-timedated.service: Succeeded. Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 19:25:36 volumio volumio[793]: info: Retrieving Cloud Streaming UI Jan 21 19:25:36 volumio volumio[793]: info: Getting Tidal Cloud Configuration Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 19:25:36 volumio volumio[793]: info: Getting Qobuz Cloud Configuration Jan 21 19:25:36 volumio volumio[793]: info: Asking plugin for UI Config Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 19:25:36 volumio volumio[793]: info: Getting Spotify Cloud Configuration Jan 21 19:25:36 volumio volumio[793]: info: Asking plugin for UI Config Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 19:25:36 volumio volumio[793]: info: Saving Spotify Acccount Jan 21 19:25:36 volumio volumio[793]: info: Got Tidal Cloud Configuration Jan 21 19:25:36 volumio volumio[793]: info: Got it Jan 21 19:25:36 volumio volumio[793]: info: Got it Jan 21 19:25:36 volumio volumio[793]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 19:25:36 volumio volumio[793]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 19:25:36 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 21 19:25:37 volumio volumio[793]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 21 19:25:38 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 21 19:25:38 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand rescan Jan 21 19:25:38 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Jan 21 19:25:38 volumio volumio[793]: info: Jan 21 19:25:38 volumio volumio[793]: ---------------------------- MPD announces state update: update Jan 21 19:25:38 volumio volumio[793]: info: sendMpdCommand rescan took 9 milliseconds Jan 21 19:25:38 volumio volumio[793]: info: ControllerMpd::getState Jan 21 19:25:38 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Jan 21 19:25:38 volumio volumio[793]: info: sendMpdCommand status took 10 milliseconds Jan 21 19:25:38 volumio volumio[793]: info: sendMpdCommand status took 4 milliseconds Jan 21 19:25:38 volumio volumio[793]: verbose: ControllerMpd::parseState Jan 21 19:25:39 volumio volumio[793]: info: Command Router : Notfying DB Updatetrue Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::parseState Jan 21 19:25:39 volumio volumio[793]: info: ControllerMpd::pushState Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::servicePushState Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 19:25:39 volumio volumio[793]: verbose: CURRENT POSITION 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: No code Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: ------------------------------ 842ms Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Jan 21 19:25:39 volumio volumio[793]: info: Jan 21 19:25:39 volumio volumio[793]: ---------------------------- MPD announces state update: update Jan 21 19:25:39 volumio volumio[793]: info: ControllerMpd::getState Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Jan 21 19:25:39 volumio volumio[793]: info: Jan 21 19:25:39 volumio volumio[793]: ---------------------------- MPD announces state update: update Jan 21 19:25:39 volumio volumio[793]: info: ControllerMpd::getState Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Jan 21 19:25:39 volumio volumio[793]: info: Jan 21 19:25:39 volumio volumio[793]: ---------------------------- MPD announces state update: update Jan 21 19:25:39 volumio volumio[793]: info: ControllerMpd::getState Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::sendMpdCommand status Jan 21 19:25:39 volumio volumio[793]: info: sendMpdCommand status took 10 milliseconds Jan 21 19:25:39 volumio volumio[793]: info: sendMpdCommand status took 8 milliseconds Jan 21 19:25:39 volumio volumio[793]: info: sendMpdCommand status took 7 milliseconds Jan 21 19:25:39 volumio volumio[793]: info: sendMpdCommand status took 5 milliseconds Jan 21 19:25:39 volumio volumio[793]: info: sendMpdCommand status took 4 milliseconds Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::parseState Jan 21 19:25:39 volumio volumio[793]: info: Command Router : Notfying DB Updatetrue Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::Close All Modals sent Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::parseState Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::parseState Jan 21 19:25:39 volumio volumio[793]: info: Command Router : Notfying DB Updatetrue Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::Close All Modals sent Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::parseState Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::parseState Jan 21 19:25:39 volumio volumio[793]: info: Command Router : Notfying DB Updatetrue Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::Close All Modals sent Jan 21 19:25:39 volumio volumio[793]: verbose: ControllerMpd::parseState Jan 21 19:25:39 volumio volumio[793]: info: ControllerMpd::pushState Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::servicePushState Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 19:25:39 volumio volumio[793]: verbose: CURRENT POSITION 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: No code Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: ControllerMpd::pushState Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::servicePushState Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 19:25:39 volumio volumio[793]: verbose: CURRENT POSITION 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: No code Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: ControllerMpd::pushState Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::servicePushState Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 21 19:25:39 volumio volumio[793]: verbose: CURRENT POSITION 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::syncState stateService stop Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::syncState currentStatus stop Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: No code Jan 21 19:25:39 volumio volumio[793]: info: CoreStateMachine::pushState Jan 21 19:25:39 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:39 volumio volumio[793]: info: CoreCommandRouter::volumioPushState Jan 21 19:25:39 volumio volumio[793]: info: ------------------------------ 232ms Jan 21 19:25:39 volumio volumio[793]: info: ------------------------------ 229ms Jan 21 19:25:39 volumio volumio[793]: info: ------------------------------ 228ms Jan 21 19:25:39 volumio volumio[793]: info: MYVOLUMIO: Adding device Jan 21 19:25:39 volumio volumio[793]: info: MYVOLUMIO: Evaluating Server Jan 21 19:25:40 volumio volumio[793]: info: Setting Geolocation for MyVolumio to eu4 Jan 21 19:25:40 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:40 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:40 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:40 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 21 19:25:41 volumio volumio[793]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 21 19:25:41 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 21 19:25:41 volumio volumio[793]: info: Updating MyVolumio device info Jan 21 19:25:41 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:42 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:42 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:42 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 19:25:42 volumio volumio[793]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 21 19:25:42 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:42 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:42 volumio volumio[793]: info: CoreCommandRouter::volumioGetQueue Jan 21 19:25:42 volumio volumio[793]: info: CoreStateMachine::getQueue Jan 21 19:25:42 volumio volumio[793]: info: CorePlayQueue::getQueue Jan 21 19:25:42 volumio volumio[793]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 21 19:25:43 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 19:25:44 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:44 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 21 19:25:44 volumio volumio-remote-updater[585]: No test mode Jan 21 19:25:44 volumio volumio-remote-updater[585]: No alpha test mode Jan 21 19:25:44 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 21 19:25:44 volumio volumio[793]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 21 19:25:44 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 21 19:25:46 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:46 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 21 19:25:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 21 19:25:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 21 19:25:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 21 19:25:47 volumio volumio[793]: info: CoreCommandRouter::volumioGetBrowseSources Jan 21 19:25:47 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 19:25:49 volumio volumio[793]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 21 19:25:49 volumio volumio[793]: info: Completed starting MyVolumio Plugin Jan 21 19:25:49 volumio volumio[793]: [Metrics] CommandRouter: 46s 80.01ms Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::volumiosetStartupVolume Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::Close All Modals sent Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::Close All Modals sent Jan 21 19:25:49 volumio volumio[793]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 21 19:25:49 volumio volumio[793]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 21 19:25:49 volumio volumio[793]: info: camilladsp stopping service pid 1217... Jan 21 19:25:49 volumio volumio[793]: info: camilladsp service terminated, instance 1 Jan 21 19:25:49 volumio volumio[793]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 21 19:25:49 volumio volumio[793]: info: camilladsp service started and running in background, instance 1 Jan 21 19:25:49 volumio volumio[793]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 21 19:25:49 volumio volumio[793]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 21 19:25:49 volumio volumio[793]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 19:25:49 volumio volumio[793]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 21 19:25:49 volumio volumio[793]: info: Received Get System Version Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 21 19:25:49 volumio volumio[793]: info: Received Get System Info Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 21 19:25:49 volumio volumio[793]: info: Discovery: Getting this device information Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::volumioGetState Jan 21 19:25:49 volumio volumio[793]: info: CorePlayQueue::getTrack 0 Jan 21 19:25:49 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 21 19:25:49 volumio volumio[793]: error: FusionDsp - WebSocket error: [object Object] Jan 21 19:25:49 volumio volumio[793]: info: camilladsp spawned new process with pid 1415, instance 1, run: true Jan 21 19:25:50 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 21 19:25:50 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 21 19:25:50 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 21 19:25:51 volumio volumio[1257]: .....................................................................................................................................++++ Jan 21 19:25:51 volumio volumio[1257]: e is 65537 (0x010001) Jan 21 19:25:51 volumio volumio[1257]: writing RSA key Jan 21 19:25:54 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 21 19:25:54 volumio volumio[793]: info: Preload queue cleared Jan 21 19:25:54 volumio volumio[793]: info: camilladsp spawned new process with pid 1510, instance 1, run: true Jan 21 19:25:55 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 21 19:25:56 volumio volumio[793]: info: Preload queue cleared Jan 21 19:25:56 volumio volumio[793]: info: BOOT COMPLETED Jan 21 19:25:58 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 21 19:25:58 volumio volumio[793]: info: Preload queue cleared Jan 21 19:25:59 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 21 19:25:59 volumio volumio[793]: info: Preload queue cleared Jan 21 19:26:00 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 21 19:26:00 volumio volumio[793]: info: Preload queue cleared Jan 21 19:26:01 volumio ntpd[750]: Soliciting pool server 51.250.68.198 Jan 21 19:26:02 volumio ntpd[750]: Soliciting pool server 85.142.172.104 Jan 21 19:26:03 volumio ntpd[750]: Soliciting pool server 91.201.254.110 Jan 21 19:26:04 volumio ntpd[750]: Soliciting pool server 92.63.177.253 Jan 21 19:26:04 volumio ntpd[750]: Soliciting pool server 176.215.178.239 Jan 21 19:26:05 volumio volumio[793]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 21 19:26:05 volumio volumio[793]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 19:26:05 volumio volumio[793]: TypeError: Cannot read property 'length' of undefined Jan 21 19:26:05 volumio volumio[793]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 21 19:26:05 volumio volumio[793]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 21 19:26:05 volumio volumio[793]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 21 19:26:05 volumio volumio[793]: at Parser.emit (events.js:315:20) Jan 21 19:26:05 volumio volumio[793]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 21 19:26:05 volumio volumio[793]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 21 19:26:05 volumio volumio[793]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 21 19:26:05 volumio volumio[793]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 21 19:26:05 volumio volumio[793]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 21 19:26:05 volumio volumio[793]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 21 19:26:05 volumio volumio[793]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 21 19:26:05 volumio volumio[793]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 21 19:26:05 volumio volumio[793]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 21 19:26:05 volumio volumio[793]: at IncomingMessage.emit (events.js:327:22) Jan 21 19:26:05 volumio volumio[793]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 21 19:26:05 volumio volumio[793]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 21 19:26:05 volumio volumio[793]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 21 19:26:05 volumio ntpd[750]: Soliciting pool server 95.163.215.213 Jan 21 19:26:05 volumio ntpd[750]: Soliciting pool server 192.36.143.130 Jan 21 19:26:06 volumio sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-21 19:25 Jan 21 19:26:06 volumio sudo[1543]: 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"