-- Logs begin at Tue 2025-03-11 03:15:46 GMT, end at Tue 2025-03-11 03:17:06 GMT. -- Mar 11 03:16:00 volumio2 dhcpcd[602]: eth0: using IPv4LL address 169.254.36.117 Mar 11 03:16:00 volumio2 dhcpcd[602]: eth0: adding route to 169.254.0.0/16 Mar 11 03:16:00 volumio2 dhcpcd[602]: eth0: adding default route Mar 11 03:16:00 volumio2 dhcpcd[602]: eth0: using IPv4LL address 169.254.36.117 Mar 11 03:16:00 volumio2 dhcpcd[602]: eth0: adding route to 169.254.0.0/16 Mar 11 03:16:00 volumio2 dhcpcd[602]: eth0: adding default route Mar 11 03:16:00 volumio2 avahi-daemon[585]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.36.117. Mar 11 03:16:00 volumio2 avahi-daemon[585]: New relevant interface eth0.IPv4 for mDNS. Mar 11 03:16:00 volumio2 avahi-daemon[585]: Registering new address record for 169.254.36.117 on eth0.IPv4. Mar 11 03:16:00 volumio2 volumio[795]: info: MPD Permissions set Mar 11 03:16:00 volumio2 dhcpcd[602]: forked to background, child pid 1175 Mar 11 03:16:00 volumio2 dhcpcd[602]: forked to background, child pid 1175 Mar 11 03:16:00 volumio2 systemd[1]: Started DHCP Client Daemon. Mar 11 03:16:00 volumio2 volumio[795]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: Starting Shairport Sync Mar 11 03:16:00 volumio2 volumio[795]: info: Starting Shairport Sync Mar 11 03:16:00 volumio2 volumio[795]: info: Starting Shairport Sync Mar 11 03:16:00 volumio2 sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 11 03:16:00 volumio2 sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:00 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 11 03:16:00 volumio2 sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 11 03:16:00 volumio2 sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:00 volumio2 systemd[1]: shairport-sync.service: Succeeded. Mar 11 03:16:00 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 11 03:16:00 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 11 03:16:00 volumio2 sudo[1184]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:00 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 11 03:16:00 volumio2 sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 11 03:16:00 volumio2 sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:00 volumio2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 11 03:16:00 volumio2 systemd[1]: shairport-sync.service: Succeeded. Mar 11 03:16:00 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 11 03:16:00 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 11 03:16:00 volumio2 sudo[1186]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:00 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 11 03:16:00 volumio2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 11 03:16:00 volumio2 systemd[1]: shairport-sync.service: Succeeded. Mar 11 03:16:00 volumio2 volumio[795]: info: Shairport-Sync Started Mar 11 03:16:00 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 11 03:16:00 volumio2 volumio[795]: Error adding Membership: Error: addMembership EINVAL Mar 11 03:16:00 volumio2 volumio[795]: info: Asound.conf file unchanged, so no further update is needed Mar 11 03:16:00 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 11 03:16:00 volumio2 sudo[1189]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:00 volumio2 volumio[795]: info: Output device has changed, restarting MPD Mar 11 03:16:00 volumio2 sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 11 03:16:00 volumio2 sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:00 volumio2 sudo[1196]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:00 volumio2 volumio[795]: info: Output device has changed, restarting Shairport Sync Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:16:00 volumio2 sudo[1201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 11 03:16:00 volumio2 sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:00 volumio2 volumio[795]: aplay: device_list:272: no soundcards found... Mar 11 03:16:00 volumio2 volumio[795]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 11 03:16:00 volumio2 volumio[795]: aplay: device_list:272: no soundcards found... Mar 11 03:16:00 volumio2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 11 03:16:00 volumio2 systemd[1]: mpd.service: Succeeded. Mar 11 03:16:00 volumio2 systemd[1]: Stopped Music Player Daemon. Mar 11 03:16:00 volumio2 volumio[795]: info: Shairport-Sync Started Mar 11 03:16:00 volumio2 volumio[795]: info: MPD Permissions set Mar 11 03:16:00 volumio2 volumio[795]: info: Shairport-Sync Started Mar 11 03:16:00 volumio2 systemd[1]: Starting Music Player Daemon... Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::volumioGetState Mar 11 03:16:00 volumio2 volumio[795]: info: CorePlayQueue::getTrack 0 Mar 11 03:16:00 volumio2 sh[527]: eth0: using IPv4LL address 169.254.36.117 Mar 11 03:16:00 volumio2 sh[527]: eth0: adding route to 169.254.0.0/16 Mar 11 03:16:00 volumio2 sh[527]: eth0: adding default route Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 dhcpcd[559]: eth0: using IPv4LL address 169.254.36.117 Mar 11 03:16:00 volumio2 dhcpcd[559]: eth0: adding route to 169.254.0.0/16 Mar 11 03:16:00 volumio2 dhcpcd[559]: eth0: adding default route Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 sh[527]: forked to background, child pid 1217 Mar 11 03:16:00 volumio2 dhcpcd[559]: forked to background, child pid 1217 Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 11 03:16:00 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:00 volumio2 volumio[795]: info: Starting Shairport Sync Mar 11 03:16:00 volumio2 sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 11 03:16:00 volumio2 sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:00 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 11 03:16:00 volumio2 systemd[1]: shairport-sync.service: Succeeded. Mar 11 03:16:00 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 11 03:16:01 volumio2 ifplugd(eth0)[783]: client: ifup: interface eth0 already configured Mar 11 03:16:01 volumio2 sh[527]: eth0=eth0 Mar 11 03:16:01 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 11 03:16:01 volumio2 sudo[1244]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:01 volumio2 volumio[795]: info: Shairport-Sync Started Mar 11 03:16:01 volumio2 ifplugd(eth0)[783]: Program executed successfully. Mar 11 03:16:01 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:01 volumio2 mpd[1247]: Mar 11 03:16 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 11 03:16:01 volumio2 mpd[1247]: Mar 11 03:16 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 11 03:16:01 volumio2 mpd[1247]: Mar 11 03:16 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 11 03:16:01 volumio2 systemd[1]: Started Music Player Daemon. Mar 11 03:16:01 volumio2 sudo[1201]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:01 volumio2 sudo[1148]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:01 volumio2 volumio[795]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Mar 11 03:16:01 volumio2 volumio[795]: cannot open device 'volumioHw': No such device Mar 11 03:16:01 volumio2 volumio[795]: error: ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Mar 11 03:16:01 volumio2 volumio[795]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Mar 11 03:16:01 volumio2 volumio[795]: cannot open device 'volumioHw': No such device Mar 11 03:16:01 volumio2 volumio[795]: info: FusionDsp loaded Mar 11 03:16:01 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 03:16:01 volumio2 sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 11 03:16:01 volumio2 sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:01 volumio2 volumio[795]: info: Reporting Fusion DSP Enabled Mar 11 03:16:01 volumio2 volumio[795]: info: Adding Signal Path Element [object Object] Mar 11 03:16:01 volumio2 volumio[795]: info: Adding fusiondspeq DSP Signal Path Element Mar 11 03:16:01 volumio2 systemd[1]: Started FusionDsp Daemon. Mar 11 03:16:01 volumio2 ntpd[784]: Listen normally on 3 eth0 169.254.36.117:123 Mar 11 03:16:01 volumio2 ntpd[784]: new interface(s) found: waking up resolver Mar 11 03:16:01 volumio2 sudo[1263]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:01 volumio2 volumio[795]: error: MPD error: The expression evaluated to a falsy value: Mar 11 03:16:01 volumio2 volumio[795]: assert.ok(self.idling) Mar 11 03:16:01 volumio2 volumio[795]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Mar 11 03:16:01 volumio2 volumio[795]: error: updateQueue error: null Mar 11 03:16:02 volumio2 volumio[795]: error: File size not found in array! Mar 11 03:16:02 volumio2 volumio[795]: info: Nulleq applied Mar 11 03:16:02 volumio2 volumio[795]: info: nulleq: Mar 11 03:16:02 volumio2 volumio[795]: type: Conv Mar 11 03:16:02 volumio2 volumio[795]: error: WebSocket error: [object Object] Mar 11 03:16:02 volumio2 volumio[795]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 3 Mar 11 03:16:03 volumio2 volumio[795]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 4 Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::volumioGetVisibleSources Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::volumioGetState Mar 11 03:16:04 volumio2 volumio[795]: info: CorePlayQueue::getTrack 0 Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 11 03:16:04 volumio2 volumio[795]: info: Received Get System Info Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 03:16:04 volumio2 volumio[795]: info: Discovery: Getting this device information Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::volumioGetState Mar 11 03:16:04 volumio2 volumio[795]: info: CorePlayQueue::getTrack 0 Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::volumioGetState Mar 11 03:16:04 volumio2 volumio[795]: info: CorePlayQueue::getTrack 0 Mar 11 03:16:04 volumio2 volumio[795]: info: Listing playlists Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 03:16:04 volumio2 volumio[795]: info: ---------convfir selected------------- Mar 11 03:16:04 volumio2 volumio[795]: info: effect true Mar 11 03:16:04 volumio2 volumio[795]: info: Dsp mode set is convfir Mar 11 03:16:04 volumio2 volumio[795]: info: << convolution filters enabled Mar 11 03:16:04 volumio2 volumio[795]: info: tools file to play : Mar 11 03:16:04 volumio2 nmbd[767]: [2025/03/11 03:16:04.764883, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 11 03:16:04 volumio2 nmbd[767]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 11 03:16:04 volumio2 systemd[1]: Started Samba NMB Daemon. Mar 11 03:16:04 volumio2 systemd[1]: Starting Samba Winbind Daemon... Mar 11 03:16:04 volumio2 volumio[795]: info: CoreCommandRouter::volumioGetState Mar 11 03:16:04 volumio2 volumio[795]: info: CorePlayQueue::getTrack 0 Mar 11 03:16:04 volumio2 winbindd[1270]: [2025/03/11 03:16:04.993072, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 11 03:16:04 volumio2 winbindd[1270]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 11 03:16:04 volumio2 winbindd[1270]: [2025/03/11 03:16:04.996988, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 11 03:16:04 volumio2 systemd[1]: Started Samba Winbind Daemon. Mar 11 03:16:04 volumio2 winbindd[1270]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 11 03:16:05 volumio2 systemd[1]: Starting Samba SMB Daemon... Mar 11 03:16:05 volumio2 smbd[1275]: [2025/03/11 03:16:05.310175, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 11 03:16:05 volumio2 systemd[1]: Started Samba SMB Daemon. Mar 11 03:16:05 volumio2 smbd[1275]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 11 03:16:05 volumio2 systemd[1]: Reached target Multi-User System. Mar 11 03:16:05 volumio2 systemd[1]: Reached target Graphical Interface. Mar 11 03:16:05 volumio2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 11 03:16:05 volumio2 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 11 03:16:05 volumio2 systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 11 03:16:05 volumio2 systemd[1]: Startup finished in 4.685s (firmware) + 2.601s (loader) + 9.209s (kernel) + 20.078s (userspace) = 36.575s. Mar 11 03:16:05 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 11 03:16:05 volumio2 volumio[795]: info: Received Get System Info Mar 11 03:16:05 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 03:16:05 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 03:16:05 volumio2 volumio[795]: info: Discovery: Getting this device information Mar 11 03:16:05 volumio2 volumio[795]: info: CoreCommandRouter::volumioGetState Mar 11 03:16:05 volumio2 volumio[795]: info: CorePlayQueue::getTrack 0 Mar 11 03:16:05 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 03:16:06 volumio2 volumio-remote-updater[592]: [2025-03-11 03:16:06] [connect] Successful connection Mar 11 03:16:06 volumio2 volumio-remote-updater[592]: [2025-03-11 03:16:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741662966 101 Mar 11 03:16:06 volumio2 volumio[795]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 Mar 11 03:16:06 volumio2 sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 11 03:16:06 volumio2 sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:06 volumio2 sudo[1285]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:06 volumio2 sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 03:16:06 volumio2 sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:06 volumio2 sudo[1287]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:06 volumio2 sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 11 03:16:06 volumio2 sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:06 volumio2 systemd[1]: Started UPnP Renderer front-end to MPD. Mar 11 03:16:06 volumio2 sudo[1292]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:06 volumio2 volumio[795]: info: Upmpdcli Daemon Started Mar 11 03:16:06 volumio2 volumio[1295]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 11 03:16:06 volumio2 volumio[1295]: ..........................++++ Mar 11 03:16:06 volumio2 volumio[795]: error: updateQueue error: null Mar 11 03:16:09 volumio2 volumio[1295]: ................................................................................................................++++ Mar 11 03:16:09 volumio2 volumio[1295]: e is 65537 (0x010001) Mar 11 03:16:09 volumio2 volumio[1295]: writing RSA key Mar 11 03:16:10 volumio2 volumio[795]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.429&uuid=dbba163738edc30bfe389e7a2a958cdb" http://updates.volumio.org/downloader-v1/track-device Mar 11 03:16:10 volumio2 volumio[795]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 11 03:16:10 volumio2 volumio[795]: Dload Upload Total Spent Left Speed Mar 11 03:16:10 volumio2 volumio[795]: [132B blob data] Mar 11 03:16:10 volumio2 volumio[795]: retrying in 5 seconds, trial 0 Mar 11 03:16:10 volumio2 volumio[795]: info: Volumio Calling Home Mar 11 03:16:17 volumio2 systemd[1]: systemd-fsckd.service: Succeeded. Mar 11 03:16:19 volumio2 volumio[795]: error: MyVolumio Plugin failed to start in a timely fashion Mar 11 03:16:19 volumio2 volumio[795]: info: BOOT COMPLETED Mar 11 03:16:19 volumio2 volumio[795]: [Metrics] CommandRouter: 27s 255.82ms Mar 11 03:16:19 volumio2 volumio[795]: info: CoreCommandRouter::volumiosetStartupVolume Mar 11 03:16:19 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 03:16:19 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:16:19 volumio2 volumio[795]: info: CoreCommandRouter::Close All Modals sent Mar 11 03:16:19 volumio2 volumio[795]: info: CoreCommandRouter::Close All Modals sent Mar 11 03:16:20 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 11 03:16:20 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 11 03:16:20 volumio2 volumio[795]: aplay: device_list:272: no soundcards found... Mar 11 03:16:20 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 11 03:16:20 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 11 03:16:29 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 11 03:16:29 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:16:29 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 11 03:16:40 volumio2 volumio[795]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.429&uuid=dbba163738edc30bfe389e7a2a958cdb" http://updates.volumio.org/downloader-v1/track-device Mar 11 03:16:40 volumio2 volumio[795]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 11 03:16:40 volumio2 volumio[795]: Dload Upload Total Spent Left Speed Mar 11 03:16:40 volumio2 volumio[795]: [1.5K blob data] Mar 11 03:16:40 volumio2 volumio[795]: retrying in 5 seconds, trial 1 Mar 11 03:16:40 volumio2 volumio[795]: info: Volumio Calling Home Mar 11 03:16:40 volumio2 volumio[795]: info: Discovery: adding 8310df6d-e904-41ef-8b1d-c0aac006cecd Mar 11 03:16:40 volumio2 volumio[795]: info: Discovery: Found device Volumio2 Mar 11 03:16:40 volumio2 volumio[795]: info: CoreCommandRouter::volumioGetState Mar 11 03:16:40 volumio2 volumio[795]: info: CorePlayQueue::getTrack 0 Mar 11 03:16:49 volumio2 dhcpcd[1217]: eth0: offered 192.168.0.10 from 192.168.0.1 Mar 11 03:16:49 volumio2 dhcpcd[1217]: eth0: probing address 192.168.0.10/24 Mar 11 03:16:50 volumio2 dhcpcd[1175]: eth0: offered 192.168.0.10 from 192.168.0.1 Mar 11 03:16:51 volumio2 dhcpcd[1175]: eth0: probing address 192.168.0.10/24 Mar 11 03:16:54 volumio2 dhcpcd[1217]: eth0: leased 192.168.0.10 for 43200 seconds Mar 11 03:16:54 volumio2 dhcpcd[1217]: eth0: adding route to 192.168.0.0/24 Mar 11 03:16:54 volumio2 dhcpcd[1217]: eth0: changing default route via 192.168.0.1 Mar 11 03:16:54 volumio2 avahi-daemon[585]: Registering new address record for 192.168.0.10 on eth0.IPv4. Mar 11 03:16:54 volumio2 dhcpcd[1175]: eth0: deleted default route Mar 11 03:16:54 volumio2 ntpd[784]: ntpd exiting on signal 15 (Terminated) Mar 11 03:16:54 volumio2 systemd[1]: Stopping Network Time Service... Mar 11 03:16:54 volumio2 systemd[1]: ntp.service: Succeeded. Mar 11 03:16:54 volumio2 systemd[1]: Stopped Network Time Service. Mar 11 03:16:54 volumio2 systemd[1]: Starting Network Time Service... Mar 11 03:16:54 volumio2 ntpd[1564]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 11 03:16:54 volumio2 ntpd[1564]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 11 03:16:54 volumio2 systemd[1]: Started Network Time Service. Mar 11 03:16:54 volumio2 dhcpcd[1217]: eth0: deleting route to 169.254.0.0/16 Mar 11 03:16:54 volumio2 dhcpcd[1175]: eth0: deleted route to 169.254.0.0/16 Mar 11 03:16:54 volumio2 ntpd[1570]: proto: precision = 0.192 usec (-22) Mar 11 03:16:54 volumio2 dhcpcd[1175]: eth0: adding route to 169.254.0.0/16 Mar 11 03:16:54 volumio2 dhcpcd[1175]: if_route (ADD): Invalid argument Mar 11 03:16:54 volumio2 dhcpcd[1175]: eth0: adding default route Mar 11 03:16:54 volumio2 dhcpcd[1175]: if_route (ADD): Invalid argument Mar 11 03:16:54 volumio2 avahi-daemon[585]: Withdrawing address record for 169.254.36.117 on eth0. Mar 11 03:16:54 volumio2 avahi-daemon[585]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.36.117. Mar 11 03:16:54 volumio2 avahi-daemon[585]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.10. Mar 11 03:16:54 volumio2 ntpd[1570]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 11 03:16:54 volumio2 ntpd[1570]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 11 03:16:54 volumio2 ntpd[1570]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 805 days ago Mar 11 03:16:54 volumio2 ntpd[1570]: Listen and drop on 0 v6wildcard [::]:123 Mar 11 03:16:54 volumio2 ntpd[1570]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 11 03:16:54 volumio2 ntpd[1570]: Listen normally on 2 lo 127.0.0.1:123 Mar 11 03:16:54 volumio2 ntpd[1570]: Listen normally on 3 eth0 192.168.0.10:123 Mar 11 03:16:54 volumio2 ntpd[1570]: Listening on routing socket on fd #20 for interface updates Mar 11 03:16:54 volumio2 ntpd[1570]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 11 03:16:54 volumio2 ntpd[1570]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 11 03:16:56 volumio2 dhcpcd[1175]: eth0: leased 192.168.0.10 for 43200 seconds Mar 11 03:16:56 volumio2 dhcpcd[1175]: eth0: adding route to 192.168.0.0/24 Mar 11 03:16:56 volumio2 dhcpcd[1175]: eth0: adding default route via 192.168.0.1 Mar 11 03:16:56 volumio2 dhcpcd[1175]: eth0: adding route to 169.254.0.0/16 Mar 11 03:16:56 volumio2 dhcpcd[1175]: if_route (ADD): Invalid argument Mar 11 03:16:56 volumio2 ntpd[1570]: Soliciting pool server 131.111.8.63 Mar 11 03:16:56 volumio2 systemd[1]: Stopping Network Time Service... Mar 11 03:16:56 volumio2 ntpd[1570]: ntpd exiting on signal 15 (Terminated) Mar 11 03:16:56 volumio2 ntpd[1570]: 131.111.8.63 local addr 192.168.0.10 -> Mar 11 03:16:56 volumio2 systemd[1]: ntp.service: Succeeded. Mar 11 03:16:56 volumio2 systemd[1]: Stopped Network Time Service. Mar 11 03:16:56 volumio2 systemd[1]: Starting Network Time Service... Mar 11 03:16:56 volumio2 ntpd[1617]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 11 03:16:56 volumio2 ntpd[1617]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 11 03:16:56 volumio2 systemd[1]: Started Network Time Service. Mar 11 03:16:56 volumio2 ntpd[1623]: proto: precision = 0.192 usec (-22) Mar 11 03:16:56 volumio2 ntpd[1623]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 11 03:16:56 volumio2 ntpd[1623]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 11 03:16:56 volumio2 ntpd[1623]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 805 days ago Mar 11 03:16:56 volumio2 ntpd[1623]: Listen and drop on 0 v6wildcard [::]:123 Mar 11 03:16:56 volumio2 ntpd[1623]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 11 03:16:56 volumio2 ntpd[1623]: Listen normally on 2 lo 127.0.0.1:123 Mar 11 03:16:56 volumio2 ntpd[1623]: Listen normally on 3 eth0 192.168.0.10:123 Mar 11 03:16:56 volumio2 ntpd[1623]: Listening on routing socket on fd #20 for interface updates Mar 11 03:16:56 volumio2 ntpd[1623]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 11 03:16:56 volumio2 ntpd[1623]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 11 03:16:56 volumio2 volumio[795]: info: Adding plugin bluetooth to MyMusic Plugins Mar 11 03:16:56 volumio2 volumio[795]: info: Adding plugin multiroom to MyMusic Plugins Mar 11 03:16:56 volumio2 volumio[795]: info: Adding plugin metavolumio to MyMusic Plugins Mar 11 03:16:56 volumio2 volumio[795]: info: Adding plugin cd_controller to MyMusic Plugins Mar 11 03:16:56 volumio2 volumio[795]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 11 03:16:56 volumio2 volumio[795]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 11 03:16:56 volumio2 volumio[795]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 11 03:16:57 volumio2 ntpd[1623]: Soliciting pool server 162.159.200.123 Mar 11 03:16:57 volumio2 volumio[795]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 11 03:16:57 volumio2 volumio[795]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 11 03:16:57 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:16:57 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:16:57 volumio2 volumio[795]: info: Starting MyVolumio Remote Streaming Endpoints Mar 11 03:16:57 volumio2 volumio[795]: info: MyVolumio login type: Token Mar 11 03:16:57 volumio2 volumio[795]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 11 03:16:57 volumio2 volumio[795]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 11 03:16:58 volumio2 ntpd[1623]: Soliciting pool server 90.255.244.219 Mar 11 03:16:58 volumio2 ntpd[1623]: Soliciting pool server 139.162.219.252 Mar 11 03:16:58 volumio2 volumio[795]: info: Starting Streaming Service Transparent Proxy Mar 11 03:16:58 volumio2 volumio[795]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 11 03:16:58 volumio2 volumio[795]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 11 03:16:58 volumio2 volumio[795]: info: Streaming services startup Mar 11 03:16:58 volumio2 volumio[795]: info: Starting Streaming Daemon Mar 11 03:16:58 volumio2 sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 11 03:16:58 volumio2 sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:16:58 volumio2 sudo[1664]: pam_unix(sudo:session): session closed for user root Mar 11 03:16:58 volumio2 volumio[795]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 11 03:16:58 volumio2 volumio[795]: error: Cannot start Volumio Streaming Daemon Mar 11 03:16:58 volumio2 volumio[795]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 11 03:16:58 volumio2 volumio[795]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 11 03:16:58 volumio2 volumio[795]: error: MyVolumio Custom Token format not valid, refreshing it Mar 11 03:16:59 volumio2 volumio[795]: STREAMING PROXY: Starting server on port 3245 Mar 11 03:16:59 volumio2 volumio[795]: Node JS runtime: 14 Mar 11 03:16:59 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 11 03:16:59 volumio2 volumio[795]: info: Received Get System Info Mar 11 03:16:59 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 03:16:59 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 03:16:59 volumio2 volumio[795]: info: Discovery: Getting this device information Mar 11 03:16:59 volumio2 volumio[795]: info: CoreCommandRouter::volumioGetState Mar 11 03:16:59 volumio2 volumio[795]: info: CorePlayQueue::getTrack 0 Mar 11 03:16:59 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 03:16:59 volumio2 ntpd[1623]: Soliciting pool server 217.114.59.66 Mar 11 03:16:59 volumio2 ntpd[1623]: Soliciting pool server 217.114.59.3 Mar 11 03:16:59 volumio2 ntpd[1623]: Soliciting pool server 81.179.123.60 Mar 11 03:16:59 volumio2 volumio[795]: info: MyVolumio login type: Token Mar 11 03:16:59 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 11 03:16:59 volumio2 volumio[795]: info: MyVolumio login type: Token Mar 11 03:17:00 volumio2 ntpd[1623]: Soliciting pool server 185.103.117.60 Mar 11 03:17:00 volumio2 ntpd[1623]: Soliciting pool server 95.215.175.2 Mar 11 03:17:00 volumio2 ntpd[1623]: Soliciting pool server 139.143.5.30 Mar 11 03:17:00 volumio2 volumio[795]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 11 03:17:00 volumio2 volumio[795]: info: MYVOLUMIO: Adding device Mar 11 03:17:00 volumio2 volumio[795]: info: MYVOLUMIO: Evaluating Server Mar 11 03:17:00 volumio2 volumio[795]: info: MyVolumio status changed Mar 11 03:17:00 volumio2 volumio[795]: info: Streaming services startup Mar 11 03:17:00 volumio2 volumio[795]: info: Starting Streaming Daemon Mar 11 03:17:01 volumio2 volumio[795]: info: Removing browser output: myVolumio user plan is not superstar Mar 11 03:17:01 volumio2 volumio[795]: info: Removing audio output: Mar 11 03:17:01 volumio2 volumio[795]: info: Stoppping Tunnel 1 Mar 11 03:17:01 volumio2 sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 11 03:17:01 volumio2 sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:17:01 volumio2 sudo[1688]: pam_unix(sudo:session): session closed for user root Mar 11 03:17:01 volumio2 sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 11 03:17:01 volumio2 sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 03:17:01 volumio2 volumio[795]: error: Cannot start Volumio Streaming Daemon Mar 11 03:17:01 volumio2 volumio[795]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 11 03:17:01 volumio2 volumio[795]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 11 03:17:01 volumio2 sudo[1691]: pam_unix(sudo:session): session closed for user root Mar 11 03:17:01 volumio2 volumio[795]: info: Remote SSH Stopped Mar 11 03:17:01 volumio2 ntpd[1623]: Soliciting pool server 162.159.200.1 Mar 11 03:17:01 volumio2 ntpd[1623]: Soliciting pool server 129.215.160.240 Mar 11 03:17:01 volumio2 ntpd[1623]: Soliciting pool server 176.58.127.165 Mar 11 03:17:01 volumio2 volumio[795]: info: Setting Geolocation for MyVolumio to eu7 Mar 11 03:17:01 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:17:01 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:17:01 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:17:01 volumio2 volumio[795]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 11 03:17:02 volumio2 ntpd[1623]: Soliciting pool server 131.111.8.61 Mar 11 03:17:02 volumio2 ntpd[1623]: Soliciting pool server 178.62.250.107 Mar 11 03:17:02 volumio2 volumio[795]: info: Updating MyVolumio device info Mar 11 03:17:02 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 03:17:02 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 11 03:17:02 volumio2 volumio[795]: info: Received Get System Info Mar 11 03:17:02 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 11 03:17:02 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 11 03:17:02 volumio2 volumio[795]: info: Discovery: Getting this device information Mar 11 03:17:02 volumio2 volumio[795]: info: CoreCommandRouter::volumioGetState Mar 11 03:17:02 volumio2 volumio[795]: info: CorePlayQueue::getTrack 0 Mar 11 03:17:02 volumio2 volumio[795]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 11 03:17:02 volumio2 volumio[795]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 11 03:17:03 volumio2 ntpd[1623]: Soliciting pool server 178.215.228.24 Mar 11 03:17:03 volumio2 ntpd[1623]: Soliciting pool server 131.111.8.63 Mar 11 03:17:04 volumio2 ntpd[1623]: Soliciting pool server 193.150.34.2 Mar 11 03:17:04 volumio2 ntpd[1623]: Soliciting pool server 132.226.210.133 Mar 11 03:17:05 volumio2 volumio[795]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.429&uuid=dbba163738edc30bfe389e7a2a958cdb" http://updates.volumio.org/downloader-v1/track-device Mar 11 03:17:05 volumio2 volumio[795]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 11 03:17:05 volumio2 volumio[795]: Dload Upload Total Spent Left Speed Mar 11 03:17:05 volumio2 volumio[795]: [1.2K blob data] Mar 11 03:17:05 volumio2 volumio[795]: retrying in 5 seconds, trial 2 Mar 11 03:17:05 volumio2 volumio[795]: info: Volumio Calling Home Mar 11 03:17:05 volumio2 volumio[795]: info: Volumio called home Mar 11 03:17:05 volumio2 volumio[795]: info: MYVOLUMIO: Adding device Mar 11 03:17:05 volumio2 volumio[795]: info: MYVOLUMIO: Evaluating Server Mar 11 03:17:06 volumio2 ntpd[1623]: receive: Unexpected origin timestamp 0xeb7a29b2.2c39bde5 does not match aorg 0000000000.00000000 from server@176.58.127.165 xmt 0xeb7a29b2.091eb9c9 Mar 11 03:17:06 volumio2 ntpd[1623]: receive: Unexpected origin timestamp 0xeb7a29b2.2c364409 does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xeb7a29b2.0abc0cb6 Mar 11 03:17:06 volumio2 ntpd[1623]: receive: Unexpected origin timestamp 0xeb7a29b2.2c3bad22 does not match aorg 0000000000.00000000 from server@139.143.5.30 xmt 0xeb7a29b2.0e366176 Mar 11 03:17:06 volumio2 ntpd[1623]: receive: Unexpected origin timestamp 0xeb7a29b2.2c3e823e does not match aorg 0000000000.00000000 from server@217.114.59.66 xmt 0xeb7a29b2.1011cf54 Mar 11 03:17:06 volumio2 ntpd[1623]: receive: Unexpected origin timestamp 0xeb7a29b2.2c3d1c4e does not match aorg 0000000000.00000000 from server@185.103.117.60 xmt 0xeb7a29b2.10b811fa Mar 11 03:17:06 volumio2 ntpd[1623]: receive: Unexpected origin timestamp 0xeb7a29b2.2c3feae6 does not match aorg 0000000000.00000000 from server@90.255.244.219 xmt 0xeb7a29b2.10f3ba1e Mar 11 03:17:06 volumio2 volumio[795]: error: Failed to ping endpoint eu4.myvolumio.org : unknown error Mar 11 03:17:06 volumio2 volumio[795]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 03:17:06 volumio2 volumio[795]: Error: Unable to resolve or reject the same promise twice Mar 11 03:17:06 volumio2 volumio[795]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 11 03:17:06 volumio2 volumio[795]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32091) Mar 11 03:17:06 volumio2 volumio[795]: at Socket.emit (events.js:400:28) Mar 11 03:17:06 volumio2 volumio[795]: at addChunk (internal/streams/readable.js:293:12) Mar 11 03:17:06 volumio2 volumio[795]: at readableAddChunk (internal/streams/readable.js:267:9) Mar 11 03:17:06 volumio2 volumio[795]: at Socket.Readable.push (internal/streams/readable.js:206:10) Mar 11 03:17:06 volumio2 volumio[795]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Mar 11 03:17:06 volumio2 volumio[795]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 03:17:06 volumio2 sudo[1722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-11 03:16 Mar 11 03:17:06 volumio2 sudo[1722]: 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="1d63a568b7d5e4a317f16be7d0961cbb2d82e282" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="4f8978e0148ab5e1610548b80abf1b4b5e03ea0a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 07 Feb 2023 05:04:19 PM CET" VOLUMIO_VERSION="3.429" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4c8dcdad83349056095d42fbad08221f"