-- Logs begin at Fri 2025-07-18 09:10:43 CST, end at Fri 2025-07-18 09:12:53 CST. -- Jul 18 09:11:00 volumiox86 sh[583]: eth0: leased 192.168.2.144 for 43200 seconds Jul 18 09:11:00 volumiox86 dhcpcd[617]: eth0: leased 192.168.2.144 for 43200 seconds Jul 18 09:11:00 volumiox86 sh[583]: eth0: adding route to 192.168.2.0/24 Jul 18 09:11:00 volumiox86 sh[583]: eth0: adding default route via 192.168.2.1 Jul 18 09:11:00 volumiox86 dhcpcd[617]: eth0: adding route to 192.168.2.0/24 Jul 18 09:11:00 volumiox86 dhcpcd[617]: eth0: adding default route via 192.168.2.1 Jul 18 09:11:00 volumiox86 ntpd[1318]: ntpd exiting on signal 15 (Terminated) Jul 18 09:11:00 volumiox86 systemd[1]: Stopping Network Time Service... Jul 18 09:11:00 volumiox86 ntpd[1318]: 202.118.1.81 local addr 192.168.2.144 -> Jul 18 09:11:00 volumiox86 systemd[1]: ntp.service: Succeeded. Jul 18 09:11:00 volumiox86 systemd[1]: Stopped Network Time Service. Jul 18 09:11:00 volumiox86 systemd[1]: Starting Network Time Service... Jul 18 09:11:00 volumiox86 ntpd[1359]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 18 09:11:00 volumiox86 ntpd[1359]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 18 09:11:00 volumiox86 systemd[1]: Started Network Time Service. Jul 18 09:11:00 volumiox86 ntpd[1365]: proto: precision = 0.192 usec (-22) Jul 18 09:11:00 volumiox86 ntpd[1365]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 18 09:11:00 volumiox86 ntpd[1365]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 18 09:11:00 volumiox86 ntpd[1365]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 934 days ago Jul 18 09:11:00 volumiox86 ntpd[1365]: Listen and drop on 0 v6wildcard [::]:123 Jul 18 09:11:00 volumiox86 ntpd[1365]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 18 09:11:00 volumiox86 ntpd[1365]: Listen normally on 2 lo 127.0.0.1:123 Jul 18 09:11:00 volumiox86 ntpd[1365]: Listen normally on 3 eth0 192.168.2.144:123 Jul 18 09:11:00 volumiox86 ntpd[1365]: Listening on routing socket on fd #20 for interface updates Jul 18 09:11:00 volumiox86 ntpd[1365]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 18 09:11:00 volumiox86 ntpd[1365]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 18 09:11:00 volumiox86 sh[583]: forked to background, child pid 1367 Jul 18 09:11:00 volumiox86 dhcpcd[617]: forked to background, child pid 1367 Jul 18 09:11:00 volumiox86 ifplugd(eth0)[877]: client: ifup: interface eth0 already configured Jul 18 09:11:00 volumiox86 sh[583]: eth0=eth0 Jul 18 09:11:00 volumiox86 ifplugd(eth0)[877]: Program executed successfully. Jul 18 09:11:01 volumiox86 ntpd[1365]: Soliciting pool server 185.209.85.222 Jul 18 09:11:02 volumiox86 volumio[946]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 18 09:11:02 volumiox86 ntpd[1365]: Soliciting pool server 118.143.17.82 Jul 18 09:11:02 volumiox86 volumio-time-update[626]: volumio-time-update-util: Fetching time from Volumio... Jul 18 09:11:03 volumiox86 volumio-time-update[626]: volumio-time-update-util: Setting system time to: 2025-07-18 09:11:02 Jul 18 09:11:03 volumiox86 sudo[1398]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-07-18 09:11:02 Jul 18 09:11:03 volumiox86 sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:11:03 volumiox86 dbus-daemon[634]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.7' (uid=0 pid=1399 comm="timedatectl set-time 2025-07-18 09:11:02 ") Jul 18 09:11:03 volumiox86 systemd[1]: Starting Time & Date Service... Jul 18 09:11:03 volumiox86 dbus-daemon[634]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 18 09:11:03 volumiox86 systemd[1]: Started Time & Date Service. Jul 18 09:11:02 volumiox86 systemd-timedated[1400]: Changed local time to Fri Jul 18 09:11:02 2025 Jul 18 09:11:02 volumiox86 sudo[1398]: pam_unix(sudo:session): session closed for user root Jul 18 09:11:02 volumiox86 volumio-time-update[626]: volumio-time-update-util: System time updated successfully. Jul 18 09:11:02 volumiox86 systemd[1]: Started Volumio Time Update Utility. Jul 18 09:11:02 volumiox86 systemd[1]: Reached target Multi-User System. Jul 18 09:11:02 volumiox86 systemd[1]: Reached target Graphical Interface. Jul 18 09:11:02 volumiox86 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 18 09:11:02 volumiox86 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 18 09:11:02 volumiox86 systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 18 09:11:02 volumiox86 systemd[1]: Startup finished in 15.878s (firmware) + 2.295s (loader) + 8.774s (kernel) + 20.293s (userspace) = 47.242s. Jul 18 09:11:02 volumiox86 ntpd[1365]: Soliciting pool server 119.28.183.184 Jul 18 09:11:02 volumiox86 volumio[946]: info: Volumio Network Manager: Network status updated: 1 Jul 18 09:11:02 volumiox86 sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 09:11:02 volumiox86 sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:11:02 volumiox86 sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 09:11:02 volumiox86 sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:11:02 volumiox86 sudo[1418]: pam_unix(sudo:session): session closed for user root Jul 18 09:11:02 volumiox86 sudo[1420]: pam_unix(sudo:session): session closed for user root Jul 18 09:11:02 volumiox86 sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 18 09:11:02 volumiox86 sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:11:02 volumiox86 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 18 09:11:02 volumiox86 sudo[1424]: pam_unix(sudo:session): session closed for user root Jul 18 09:11:02 volumiox86 volumio[946]: info: Upmpdcli Daemon Started Jul 18 09:11:02 volumiox86 volumio[1426]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 18 09:11:03 volumiox86 ntpd[1365]: Soliciting pool server 119.28.206.193 Jul 18 09:11:04 volumiox86 ntpd[1365]: Soliciting pool server 84.16.67.12 Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 18 09:11:04 volumiox86 volumio[946]: info: Adding plugin bluetooth to MyMusic Plugins Jul 18 09:11:04 volumiox86 volumio[946]: info: Adding plugin multiroom to MyMusic Plugins Jul 18 09:11:04 volumiox86 volumio[946]: info: Adding plugin metavolumio to MyMusic Plugins Jul 18 09:11:04 volumiox86 volumio[946]: info: Adding plugin cd_controller to MyMusic Plugins Jul 18 09:11:04 volumiox86 volumio[946]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 18 09:11:04 volumiox86 volumio[946]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 18 09:11:04 volumiox86 volumio[946]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 18 09:11:04 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 18 09:11:05 volumiox86 volumio[946]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 18 09:11:05 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 18 09:11:05 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:05 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:05 volumiox86 volumio[946]: info: Starting MyVolumio Remote Streaming Endpoints Jul 18 09:11:05 volumiox86 volumio[946]: info: MyVolumio login type: Token Jul 18 09:11:05 volumiox86 volumio[946]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 18 09:11:05 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 18 09:11:06 volumiox86 volumio[946]: info: Starting Streaming Service Transparent Proxy Jul 18 09:11:06 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 18 09:11:06 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 18 09:11:06 volumiox86 volumio[946]: info: Streaming services startup Jul 18 09:11:06 volumiox86 volumio[946]: info: Starting Streaming Daemon Jul 18 09:11:06 volumiox86 volumio[946]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 18 09:11:06 volumiox86 sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 18 09:11:06 volumiox86 sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:11:06 volumiox86 sudo[1450]: pam_unix(sudo:session): session closed for user root Jul 18 09:11:06 volumiox86 volumio[946]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.819&uuid=c2fa2b05ab5d159744e1be58186a7c82" http://updates.volumio.org/downloader-v1/track-device Jul 18 09:11:06 volumiox86 volumio[946]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 18 09:11:06 volumiox86 volumio[946]: Dload Upload Total Spent Left Speed Jul 18 09:11:06 volumiox86 volumio[946]: [132B blob data] Jul 18 09:11:06 volumiox86 volumio[946]: retrying in 5 seconds, trial 0 Jul 18 09:11:06 volumiox86 volumio[946]: info: Volumio Calling Home Jul 18 09:11:06 volumiox86 volumio[946]: error: Cannot start Volumio Streaming Daemon Jul 18 09:11:06 volumiox86 volumio[946]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 18 09:11:06 volumiox86 volumio[946]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 18 09:11:07 volumiox86 volumio[946]: STREAMING PROXY: Starting server on port 3245 Jul 18 09:11:07 volumiox86 volumio[946]: Node JS runtime: 14 Jul 18 09:11:07 volumiox86 volumio[946]: info: Volumio called home Jul 18 09:11:07 volumiox86 volumio[946]: error: MyVolumio Custom Token format not valid, refreshing it Jul 18 09:11:08 volumiox86 volumio[1426]: .................................................................................................................................................................................................................................++++ Jul 18 09:11:08 volumiox86 volumio[1426]: .....++++ Jul 18 09:11:08 volumiox86 volumio[1426]: e is 65537 (0x010001) Jul 18 09:11:08 volumiox86 volumio[1426]: writing RSA key Jul 18 09:11:09 volumiox86 volumio[946]: info: MyVolumio login type: Token Jul 18 09:11:13 volumiox86 volumio[946]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 18 09:11:14 volumiox86 volumio[946]: info: MyVolumio token set successfully Jul 18 09:11:14 volumiox86 volumio[946]: info: MYVOLUMIO: Adding device Jul 18 09:11:14 volumiox86 volumio[946]: info: MYVOLUMIO: Evaluating Server Jul 18 09:11:14 volumiox86 volumio[946]: info: MyVolumio status changed Jul 18 09:11:14 volumiox86 volumio[946]: info: Streaming services startup Jul 18 09:11:14 volumiox86 volumio[946]: info: Starting Streaming Daemon Jul 18 09:11:14 volumiox86 volumio[946]: info: Removing browser output: myVolumio user plan is not superstar Jul 18 09:11:14 volumiox86 volumio[946]: info: Removing audio output: Jul 18 09:11:14 volumiox86 volumio[946]: info: Stoppping Tunnel 1 Jul 18 09:11:14 volumiox86 sudo[1537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 18 09:11:14 volumiox86 sudo[1537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:11:14 volumiox86 sudo[1539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 18 09:11:14 volumiox86 sudo[1537]: pam_unix(sudo:session): session closed for user root Jul 18 09:11:14 volumiox86 sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:11:14 volumiox86 volumio[946]: error: Cannot start Volumio Streaming Daemon Jul 18 09:11:14 volumiox86 volumio[946]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 18 09:11:14 volumiox86 volumio[946]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 18 09:11:14 volumiox86 sudo[1539]: pam_unix(sudo:session): session closed for user root Jul 18 09:11:14 volumiox86 volumio[946]: info: Remote SSH Stopped Jul 18 09:11:15 volumiox86 systemd[1]: systemd-fsckd.service: Succeeded. Jul 18 09:11:15 volumiox86 volumio[946]: info: AutoStart - Plugin is starting Jul 18 09:11:15 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetQueue Jul 18 09:11:15 volumiox86 volumio[946]: info: CoreStateMachine::getQueue Jul 18 09:11:15 volumiox86 volumio[946]: info: CorePlayQueue::getQueue Jul 18 09:11:16 volumiox86 volumio[946]: info: Setting Geolocation for MyVolumio to us2 Jul 18 09:11:16 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:16 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:16 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:17 volumiox86 volumio[946]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 18 09:11:17 volumiox86 volumio[946]: info: Updating MyVolumio device info Jul 18 09:11:17 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:17 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:17 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:18 volumiox86 volumio[946]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 18 09:11:21 volumiox86 volumio[946]: info: MYVOLUMIO: Adding device Jul 18 09:11:21 volumiox86 volumio[946]: info: MYVOLUMIO: Evaluating Server Jul 18 09:11:21 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 18 09:11:21 volumiox86 volumio[946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 18 09:11:21 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetState Jul 18 09:11:21 volumiox86 volumio[946]: info: CorePlayQueue::getTrack 0 Jul 18 09:11:24 volumiox86 volumio[946]: info: Setting Geolocation for MyVolumio to us2 Jul 18 09:11:24 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:24 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:24 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:25 volumiox86 volumio[946]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 18 09:11:25 volumiox86 volumio[946]: info: Updating MyVolumio device info Jul 18 09:11:25 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:25 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 18 09:11:26 volumiox86 volumio-remote-updater[638]: No test mode Jul 18 09:11:26 volumiox86 volumio-remote-updater[638]: No alpha test mode Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 18 09:11:26 volumiox86 volumio[946]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 09:11:26 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 09:11:27 volumiox86 volumio[946]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 18 09:11:27 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 18 09:11:27 volumiox86 volumio[946]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 18 09:11:27 volumiox86 volumio[946]: info: Completed starting MyVolumio Plugin Jul 18 09:11:27 volumiox86 volumio[946]: [Metrics] CommandRouter: 36s 33.89ms Jul 18 09:11:27 volumiox86 volumio[946]: info: CoreCommandRouter::volumiosetStartupVolume Jul 18 09:11:27 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:11:27 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:11:27 volumiox86 volumio[946]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:11:27 volumiox86 volumio[946]: info: CoreCommandRouter::Close All Modals sent Jul 18 09:11:27 volumiox86 volumio[946]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jul 18 09:11:27 volumiox86 volumio[946]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jul 18 09:11:27 volumiox86 volumio[946]: aplay: main:828: audio open error: No such device Jul 18 09:11:28 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 18 09:11:28 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 18 09:11:28 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jul 18 09:11:28 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 18 09:11:33 volumiox86 systemd[1]: systemd-timedated.service: Succeeded. Jul 18 09:11:34 volumiox86 volumio[946]: info: BOOT COMPLETED Jul 18 09:12:05 volumiox86 ntpd[1365]: Soliciting pool server 202.118.1.81 Jul 18 09:12:08 volumiox86 ntpd[1365]: Soliciting pool server 47.243.51.23 Jul 18 09:12:09 volumiox86 ntpd[1365]: Soliciting pool server 202.118.1.130 Jul 18 09:12:10 volumiox86 ntpd[1365]: Soliciting pool server 139.199.215.251 Jul 18 09:12:11 volumiox86 ntpd[1365]: Soliciting pool server 193.182.111.141 Jul 18 09:12:11 volumiox86 ntpd[1365]: Soliciting pool server 111.230.189.174 Jul 18 09:12:12 volumiox86 ntpd[1365]: Soliciting pool server 240e:980:b65:e900:bd10:673a:3f2a:f0e2 Jul 18 09:12:23 volumiox86 sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 09:12:23 volumiox86 sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:12:23 volumiox86 sudo[1693]: pam_unix(sudo:session): session closed for user root Jul 18 09:12:23 volumiox86 sudo[1695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 09:12:23 volumiox86 sudo[1695]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:12:23 volumiox86 sudo[1695]: pam_unix(sudo:session): session closed for user root Jul 18 09:12:23 volumiox86 volumio[946]: verbose: New Socket.io Connection to 192.168.2.144 from 192.168.2.143 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 4 Jul 18 09:12:23 volumiox86 sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 09:12:23 volumiox86 sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:12:23 volumiox86 sudo[1699]: pam_unix(sudo:session): session closed for user root Jul 18 09:12:23 volumiox86 sudo[1701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 09:12:23 volumiox86 sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:12:23 volumiox86 sudo[1701]: pam_unix(sudo:session): session closed for user root Jul 18 09:12:23 volumiox86 volumio[946]: verbose: New Socket.io Connection to 192.168.2.144 from 192.168.2.143 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 5 Jul 18 09:12:23 volumiox86 volumio[946]: verbose: New Socket.io Connection to 192.168.2.144 from 192.168.2.143 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 6 Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetVisibleSources Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetState Jul 18 09:12:23 volumiox86 volumio[946]: info: CorePlayQueue::getTrack 0 Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetQueue Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreStateMachine::getQueue Jul 18 09:12:23 volumiox86 volumio[946]: info: CorePlayQueue::getQueue Jul 18 09:12:23 volumiox86 volumio[946]: info: Listing playlists Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 18 09:12:23 volumiox86 volumio[946]: info: Received Get System Info Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetState Jul 18 09:12:23 volumiox86 volumio[946]: info: CorePlayQueue::getTrack 0 Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 18 09:12:23 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 18 09:12:25 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 18 09:12:25 volumiox86 volumio[946]: info: Received Get System Info Jul 18 09:12:25 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:12:25 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:12:25 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 18 09:12:25 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 18 09:12:29 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 18 09:12:29 volumiox86 volumio[946]: info: Received Get System Info Jul 18 09:12:29 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:12:29 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:12:29 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 18 09:12:29 volumiox86 volumio[946]: info: Received Get System Info Jul 18 09:12:29 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:12:29 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:12:29 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 18 09:12:29 volumiox86 volumio[946]: info: Received Get System Info Jul 18 09:12:29 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:12:29 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:12:30 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetState Jul 18 09:12:30 volumiox86 volumio[946]: info: CorePlayQueue::getTrack 0 Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 09:12:32 volumiox86 volumio[946]: info: Retrieving Cloud Streaming UI Jul 18 09:12:32 volumiox86 volumio[946]: info: Getting Tidal Cloud Configuration Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 09:12:32 volumiox86 volumio[946]: info: Getting Qobuz Cloud Configuration Jul 18 09:12:32 volumiox86 volumio[946]: info: Asking plugin for UI Config Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 09:12:32 volumiox86 volumio[946]: info: Getting Spotify Cloud Configuration Jul 18 09:12:32 volumiox86 volumio[946]: info: Asking plugin for UI Config Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 09:12:32 volumiox86 volumio[946]: info: Saving Spotify Acccount Jul 18 09:12:32 volumiox86 volumio[946]: info: Got Tidal Cloud Configuration Jul 18 09:12:32 volumiox86 volumio[946]: info: Got it Jul 18 09:12:32 volumiox86 volumio[946]: info: Got it Jul 18 09:12:32 volumiox86 volumio[946]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 18 09:12:32 volumiox86 volumio[946]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::volumioGetBrowseSources Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 09:12:32 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 18 09:12:33 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 18 09:12:36 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 09:12:40 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 09:12:42 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 18 09:12:42 volumiox86 volumio[946]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 18 09:12:42 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 18 09:12:42 volumiox86 volumio[946]: info: Received Get System Version Jul 18 09:12:42 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 09:12:42 volumiox86 volumio[946]: info: Received Get System Info Jul 18 09:12:42 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 18 09:12:42 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 18 09:12:44 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 09:12:48 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 09:12:52 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 18 09:12:53 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 18 09:12:53 volumiox86 volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 18 09:12:53 volumiox86 volumio[946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:12:53 volumiox86 volumio[946]: TypeError: Cannot read property 'then' of undefined Jul 18 09:12:53 volumiox86 volumio[946]: at ControllerNetworkfs.discoverShares (/volumio/app/plugins/system_controller/networkfs/index.js:803:11) Jul 18 09:12:53 volumiox86 volumio[946]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Jul 18 09:12:53 volumiox86 volumio[946]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1603:45) Jul 18 09:12:53 volumiox86 volumio[946]: at Socket.emit (events.js:400:28) Jul 18 09:12:53 volumiox86 volumio[946]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 18 09:12:53 volumiox86 volumio[946]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jul 18 09:12:53 volumiox86 volumio[946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:12:53 volumiox86 sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 09:11 Jul 18 09:12:53 volumiox86 sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:41:06 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="097e6b8b481766e7a0f9527e4a34a29b"