-- Logs begin at Thu 2025-12-25 15:03:48 UTC, end at Thu 2025-12-25 15:05:52 UTC. -- Dec 25 15:04:01 volumio volumio[1218]: Cannot compose Albumart path Dec 25 15:04:04 volumio volumio-time-update[836]: volumio-time-update-util: Fetching time from Volumio... Dec 25 15:04:04 volumio volumio-time-update[836]: volumio-time-update-util: Date not found in response Dec 25 15:04:04 volumio volumio-time-update[836]: volumio-time-update-util: Retrying in 5 seconds... Dec 25 15:04:04 volumio dhcpcd[932]: wlan0: using IPv4LL address 169.254.122.42 Dec 25 15:04:04 volumio dhcpcd[932]: wlan0: adding route to 169.254.0.0/16 Dec 25 15:04:04 volumio dhcpcd[932]: wlan0: adding default route Dec 25 15:04:04 volumio avahi-daemon[840]: Registering new address record for 169.254.122.42 on wlan0.IPv4. Dec 25 15:04:04 volumio dnsmasq[1250]: reading /etc/resolv.conf Dec 25 15:04:04 volumio dnsmasq[1250]: using nameserver 208.67.222.222#53 Dec 25 15:04:04 volumio dnsmasq[1250]: using nameserver 208.67.220.220#53 Dec 25 15:04:04 volumio sudo[1585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 25 15:04:04 volumio sudo[1585]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:04:04 volumio sudo[1585]: pam_unix(sudo:session): session closed for user root Dec 25 15:04:04 volumio sudo[1588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 25 15:04:04 volumio sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:04:04 volumio sudo[1588]: pam_unix(sudo:session): session closed for user root Dec 25 15:04:04 volumio sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 25 15:04:04 volumio sudo[1591]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:04:04 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 25 15:04:04 volumio sudo[1591]: pam_unix(sudo:session): session closed for user root Dec 25 15:04:04 volumio volumio[1218]: info: Upmpdcli Daemon Started Dec 25 15:04:04 volumio volumio[1593]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 25 15:04:05 volumio volumio[1593]: ...........................................................++++ Dec 25 15:04:05 volumio volumio[1218]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.874&uuid=870e53b27678f13b5951b9e4257832b7" http://updates.volumio.org/downloader-v1/track-device Dec 25 15:04:05 volumio volumio[1218]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 25 15:04:05 volumio volumio[1218]: Dload Upload Total Spent Left Speed Dec 25 15:04:05 volumio volumio[1218]: [132B blob data] Dec 25 15:04:05 volumio volumio[1218]: retrying in 5 seconds, trial 0 Dec 25 15:04:05 volumio volumio[1218]: info: Volumio Calling Home Dec 25 15:04:05 volumio volumio[1593]: .....................................++++ Dec 25 15:04:05 volumio volumio[1593]: e is 65537 (0x010001) Dec 25 15:04:05 volumio volumio[1593]: writing RSA key Dec 25 15:04:06 volumio ntpd[1030]: Listen normally on 4 wlan0 169.254.122.42:123 Dec 25 15:04:06 volumio ntpd[1030]: new interface(s) found: waking up resolver Dec 25 15:04:09 volumio volumio-time-update[836]: volumio-time-update-util: Fetching time from Volumio... Dec 25 15:04:15 volumio volumio[1218]: error: MyVolumio Plugin failed to start in a timely fashion Dec 25 15:04:15 volumio volumio[1218]: [Metrics] CommandRouter: 21s 899.49ms Dec 25 15:04:15 volumio volumio[1218]: info: CoreCommandRouter::volumiosetStartupVolume Dec 25 15:04:15 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:04:15 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:04:15 volumio volumio[1218]: info: CoreCommandRouter::Close All Modals sent Dec 25 15:04:15 volumio volumio[1218]: info: CoreCommandRouter::Close All Modals sent Dec 25 15:04:16 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 25 15:04:16 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 15:04:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 25 15:04:18 volumio systemd[1]: systemd-fsckd.service: Succeeded. Dec 25 15:04:19 volumio sh[763]: timed out Dec 25 15:04:19 volumio dhcpcd[809]: timed out Dec 25 15:04:19 volumio sh[763]: dhcpcd exited Dec 25 15:04:19 volumio dhcpcd[809]: dhcpcd exited Dec 25 15:04:19 volumio sh[763]: ifup: failed to bring up eth0 Dec 25 15:04:19 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 25 15:04:19 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 25 15:04:22 volumio volumio[1218]: info: BOOT COMPLETED Dec 25 15:04:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 15:04:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:04:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 25 15:04:29 volumio volumio-time-update[836]: volumio-time-update-util: Date not found in response Dec 25 15:04:29 volumio volumio-time-update[836]: volumio-time-update-util: Retrying in 5 seconds... Dec 25 15:04:34 volumio volumio-time-update[836]: volumio-time-update-util: Fetching time from Volumio... Dec 25 15:04:35 volumio volumio[1218]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.874&uuid=870e53b27678f13b5951b9e4257832b7" http://updates.volumio.org/downloader-v1/track-device Dec 25 15:04:35 volumio volumio[1218]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 25 15:04:35 volumio volumio[1218]: Dload Upload Total Spent Left Speed Dec 25 15:04:35 volumio volumio[1218]: [1.6K blob data] Dec 25 15:04:35 volumio volumio[1218]: retrying in 5 seconds, trial 1 Dec 25 15:04:35 volumio volumio[1218]: info: Volumio Calling Home Dec 25 15:04:54 volumio volumio-time-update[836]: volumio-time-update-util: Date not found in response Dec 25 15:04:54 volumio volumio-time-update[836]: volumio-time-update-util: Retrying in 5 seconds... Dec 25 15:04:59 volumio volumio-time-update[836]: volumio-time-update-util: Fetching time from Volumio... Dec 25 15:05:05 volumio volumio[1218]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.874&uuid=870e53b27678f13b5951b9e4257832b7" http://updates.volumio.org/downloader-v1/track-device Dec 25 15:05:05 volumio volumio[1218]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 25 15:05:05 volumio volumio[1218]: Dload Upload Total Spent Left Speed Dec 25 15:05:05 volumio volumio[1218]: [1.6K blob data] Dec 25 15:05:05 volumio volumio[1218]: retrying in 5 seconds, trial 2 Dec 25 15:05:05 volumio volumio[1218]: info: Volumio Calling Home Dec 25 15:05:16 volumio ntpd[1030]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 25 15:05:16 volumio kernel: usb 1-3: USB disconnect, device number 4 Dec 25 15:05:16 volumio volumio[1218]: info: Dec 25 15:05:16 volumio volumio[1218]: ---------------------------- USB Audio Device Detached Dec 25 15:05:16 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 25 15:05:16 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 25 15:05:17 volumio kernel: usb 1-3: new high-speed USB device number 6 using xhci_hcd Dec 25 15:05:17 volumio kernel: usb 1-3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Dec 25 15:05:17 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Dec 25 15:05:17 volumio kernel: usb 1-3: Product: SMSL USB AUDIO Dec 25 15:05:17 volumio kernel: usb 1-3: Manufacturer: SMSL Dec 25 15:05:17 volumio kernel: usb 1-3: 1:3 : unsupported format bits 0x100000000 Dec 25 15:05:17 volumio kernel: hid-generic 0003:152A:85DD.0004: hiddev1,hidraw2: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:00:14.0-3/input3 Dec 25 15:05:17 volumio volumio[1218]: info: Dec 25 15:05:17 volumio volumio[1218]: ---------------------------- USB Audio Device Attached Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::Close All Modals sent Dec 25 15:05:17 volumio volumio[1218]: info: Preparing to save Alsa Options, stopping services first Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Dec 25 15:05:17 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::volumioPause Dec 25 15:05:17 volumio volumio[1218]: info: CoreStateMachine::pause Dec 25 15:05:17 volumio volumio[1218]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 15:05:17 volumio volumio[1218]: info: Device 5 does not have any Mixer Control Available Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 25 15:05:17 volumio volumio[1218]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 25 15:05:17 volumio volumio[1218]: info: Disabling external Volume Control Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 25 15:05:17 volumio volumio[1218]: info: CoreStateMachine::pushState Dec 25 15:05:17 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Dec 25 15:05:17 volumio volumio[1218]: info: Preparing to generate the ALSA configuration file Dec 25 15:05:17 volumio volumio[1218]: info: Asound.conf file unchanged, so no further update is needed Dec 25 15:05:17 volumio volumio[1218]: info: Output device has changed, restarting MPD Dec 25 15:05:17 volumio sudo[1823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 15:05:17 volumio sudo[1823]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:17 volumio sudo[1823]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:17 volumio sudo[1825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 15:05:17 volumio sudo[1825]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:17 volumio systemd[1]: Stopping Music Player Daemon... Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 15:05:17 volumio systemd[1]: mpd.service: Succeeded. Dec 25 15:05:17 volumio systemd[1]: Stopped Music Player Daemon. Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 25 15:05:17 volumio volumio[1218]: info: MPD Permissions set Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 25 15:05:17 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:17 volumio systemd[1]: Starting Music Player Daemon... Dec 25 15:05:17 volumio sudo[1832]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 15:05:17 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:17 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:17 volumio mpd[1840]: Dec 25 15:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 25 15:05:17 volumio systemd[1]: Started Music Player Daemon. Dec 25 15:05:17 volumio sudo[1825]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:17 volumio volumio[1218]: error: updateQueue error: null Dec 25 15:05:18 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 25 15:05:19 volumio volumio-time-update[836]: volumio-time-update-util: Date not found in response Dec 25 15:05:19 volumio volumio-time-update[836]: volumio-time-update-util: Retrying in 5 seconds... Dec 25 15:05:20 volumio sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 25 15:05:20 volumio sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:20 volumio systemd[1]: Stopping MPD Monitor Service... Dec 25 15:05:20 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 25 15:05:20 volumio systemd[1]: mpd_monitor.service: Succeeded. Dec 25 15:05:20 volumio systemd[1]: Stopped MPD Monitor Service. Dec 25 15:05:20 volumio systemd[1]: Started MPD Monitor Service. Dec 25 15:05:20 volumio sudo[1848]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:20 volumio mpd_monitor.sh[1850]: MPD Monitor Service: Starting MPD Monitor Service Dec 25 15:05:20 volumio volumio[1218]: info: Successfully started MPD Monitor Dec 25 15:05:21 volumio volumio[1218]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 25 15:05:21 volumio volumio[1218]: info: CoreStateMachine::getcurrentVolume Dec 25 15:05:21 volumio volumio[1218]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 15:05:21 volumio volumio[1218]: info: CoreStateMachine::pushState Dec 25 15:05:21 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Dec 25 15:05:21 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Dec 25 15:05:24 volumio volumio-time-update[836]: volumio-time-update-util: Fetching time from Volumio... Dec 25 15:05:24 volumio kernel: usb 1-3: USB disconnect, device number 6 Dec 25 15:05:24 volumio volumio[1218]: info: Dec 25 15:05:24 volumio volumio[1218]: ---------------------------- USB Audio Device Detached Dec 25 15:05:24 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 25 15:05:24 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 15:05:24 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 25 15:05:24 volumio kernel: usb 1-3: new high-speed USB device number 7 using xhci_hcd Dec 25 15:05:25 volumio kernel: usb 1-3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Dec 25 15:05:25 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Dec 25 15:05:25 volumio kernel: usb 1-3: Product: SMSL USB AUDIO Dec 25 15:05:25 volumio kernel: usb 1-3: Manufacturer: SMSL Dec 25 15:05:25 volumio kernel: usb 1-3: 1:3 : unsupported format bits 0x100000000 Dec 25 15:05:25 volumio kernel: hid-generic 0003:152A:85DD.0005: hiddev1,hidraw2: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:00:14.0-3/input3 Dec 25 15:05:25 volumio volumio[1218]: info: Dec 25 15:05:25 volumio volumio[1218]: ---------------------------- USB Audio Device Attached Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::Close All Modals sent Dec 25 15:05:25 volumio volumio[1218]: info: Preparing to save Alsa Options, stopping services first Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Dec 25 15:05:25 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::volumioPause Dec 25 15:05:25 volumio volumio[1218]: info: CoreStateMachine::pause Dec 25 15:05:25 volumio volumio[1218]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 15:05:25 volumio volumio[1218]: info: Device 5 does not have any Mixer Control Available Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 25 15:05:25 volumio volumio[1218]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 25 15:05:25 volumio volumio[1218]: info: Disabling external Volume Control Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 25 15:05:25 volumio volumio[1218]: info: CoreStateMachine::pushState Dec 25 15:05:25 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Dec 25 15:05:25 volumio volumio[1218]: info: Preparing to generate the ALSA configuration file Dec 25 15:05:25 volumio volumio[1218]: info: Asound.conf file unchanged, so no further update is needed Dec 25 15:05:25 volumio volumio[1218]: info: Output device has changed, restarting MPD Dec 25 15:05:25 volumio sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 25 15:05:25 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:25 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:25 volumio sudo[1896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 25 15:05:25 volumio sudo[1896]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:25 volumio systemd[1]: Stopping Music Player Daemon... Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 15:05:25 volumio systemd[1]: mpd.service: Succeeded. Dec 25 15:05:25 volumio systemd[1]: Stopped Music Player Daemon. Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 25 15:05:25 volumio volumio[1218]: info: MPD Permissions set Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 15:05:25 volumio systemd[1]: Starting Music Player Daemon... Dec 25 15:05:25 volumio sudo[1902]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 25 15:05:25 volumio sudo[1902]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:25 volumio sudo[1902]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:25 volumio mpd[1911]: Dec 25 15:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 25 15:05:25 volumio systemd[1]: Started Music Player Daemon. Dec 25 15:05:25 volumio sudo[1896]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:25 volumio volumio[1218]: error: updateQueue error: null Dec 25 15:05:25 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Dec 25 15:05:27 volumio dhcpcd[932]: eth0: carrier acquired Dec 25 15:05:27 volumio kernel: r8169 0000:01:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Dec 25 15:05:27 volumio dhcpcd[932]: eth0: IAID 1e:d3:b4:ff Dec 25 15:05:27 volumio dhcpcd[932]: eth0: soliciting an IPv6 router Dec 25 15:05:27 volumio ifplugd(eth0)[1021]: Link beat detected. Dec 25 15:05:27 volumio ifplugd(eth0)[1021]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 25 15:05:27 volumio dhcpcd[1934]: sending commands to master dhcpcd process Dec 25 15:05:27 volumio dhcpcd[932]: control command: /sbin/dhcpcd eth0 Dec 25 15:05:27 volumio ifplugd(eth0)[1021]: client: sending commands to master dhcpcd process Dec 25 15:05:28 volumio ifplugd(eth0)[1021]: Program executed successfully. Dec 25 15:05:28 volumio dhcpcd[932]: eth0: soliciting a DHCP lease Dec 25 15:05:28 volumio sudo[1960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 25 15:05:28 volumio sudo[1960]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:28 volumio systemd[1]: Stopping MPD Monitor Service... Dec 25 15:05:28 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 25 15:05:28 volumio systemd[1]: mpd_monitor.service: Succeeded. Dec 25 15:05:28 volumio systemd[1]: Stopped MPD Monitor Service. Dec 25 15:05:28 volumio systemd[1]: Started MPD Monitor Service. Dec 25 15:05:28 volumio sudo[1960]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:28 volumio mpd_monitor.sh[1962]: MPD Monitor Service: Starting MPD Monitor Service Dec 25 15:05:28 volumio volumio[1218]: info: Successfully started MPD Monitor Dec 25 15:05:29 volumio volumio[1218]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 25 15:05:29 volumio volumio[1218]: info: CoreStateMachine::getcurrentVolume Dec 25 15:05:29 volumio volumio[1218]: info: CoreCommandRouter::volumioRetrievevolume Dec 25 15:05:29 volumio volumio[1218]: info: CoreStateMachine::pushState Dec 25 15:05:29 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Dec 25 15:05:29 volumio volumio[1218]: info: CoreCommandRouter::volumioPushState Dec 25 15:05:31 volumio dhcpcd[932]: eth0: offered 192.168.1.209 from 192.168.1.1 Dec 25 15:05:31 volumio dhcpcd[932]: eth0: probing address 192.168.1.209/24 Dec 25 15:05:35 volumio dhcpcd[932]: eth0: leased 192.168.1.209 for 43200 seconds Dec 25 15:05:35 volumio avahi-daemon[840]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.209. Dec 25 15:05:35 volumio dhcpcd[932]: eth0: adding route to 192.168.1.0/24 Dec 25 15:05:35 volumio dhcpcd[932]: eth0: adding default route via 192.168.1.1 Dec 25 15:05:35 volumio avahi-daemon[840]: New relevant interface eth0.IPv4 for mDNS. Dec 25 15:05:35 volumio avahi-daemon[840]: Registering new address record for 192.168.1.209 on eth0.IPv4. Dec 25 15:05:35 volumio dhcpcd[932]: wlan0: deleting default route Dec 25 15:05:35 volumio dnsmasq[1250]: reading /etc/resolv.conf Dec 25 15:05:35 volumio dnsmasq[1250]: using nameserver 192.168.1.1#53 Dec 25 15:05:35 volumio dnsmasq[1250]: using nameserver 208.67.222.222#53 Dec 25 15:05:35 volumio dnsmasq[1250]: using nameserver 208.67.220.220#53 Dec 25 15:05:35 volumio ntpd[1030]: ntpd exiting on signal 15 (Terminated) Dec 25 15:05:35 volumio systemd[1]: Stopping Network Time Service... Dec 25 15:05:35 volumio systemd[1]: ntp.service: Succeeded. Dec 25 15:05:35 volumio systemd[1]: Stopped Network Time Service. Dec 25 15:05:35 volumio systemd[1]: Starting Network Time Service... Dec 25 15:05:35 volumio ntpd[2014]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 25 15:05:35 volumio ntpd[2014]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 25 15:05:35 volumio systemd[1]: Started Network Time Service. Dec 25 15:05:35 volumio ntpd[2020]: proto: precision = 0.043 usec (-24) Dec 25 15:05:35 volumio ntpd[2020]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 25 15:05:35 volumio ntpd[2020]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 25 15:05:35 volumio ntpd[2020]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1094 days ago Dec 25 15:05:35 volumio ntpd[2020]: Listen and drop on 0 v6wildcard [::]:123 Dec 25 15:05:35 volumio ntpd[2020]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 25 15:05:35 volumio ntpd[2020]: Listen normally on 2 lo 127.0.0.1:123 Dec 25 15:05:35 volumio ntpd[2020]: Listen normally on 3 eth0 192.168.1.209:123 Dec 25 15:05:35 volumio ntpd[2020]: Listen normally on 4 wlan0 192.168.211.1:123 Dec 25 15:05:35 volumio ntpd[2020]: Listen normally on 5 wlan0 169.254.122.42:123 Dec 25 15:05:35 volumio ntpd[2020]: Listening on routing socket on fd #22 for interface updates Dec 25 15:05:35 volumio ntpd[2020]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 25 15:05:35 volumio ntpd[2020]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 25 15:05:37 volumio ntpd[2020]: Soliciting pool server 31.56.32.39 Dec 25 15:05:37 volumio ntpd[2020]: Soliciting pool server 89.36.162.11 Dec 25 15:05:38 volumio volumio[1218]: info: Discovery: this is already registered, 2f1dbe76-8c21-4865-acf8-7ef5fd9f8307 Dec 25 15:05:38 volumio volumio[1218]: info: Discovery: Found device Volumio Dec 25 15:05:38 volumio volumio[1218]: info: CoreCommandRouter::volumioGetState Dec 25 15:05:38 volumio volumio[1218]: info: CorePlayQueue::getTrack 0 Dec 25 15:05:38 volumio ntpd[2020]: Soliciting pool server 5.32.10.107 Dec 25 15:05:38 volumio ntpd[2020]: Soliciting pool server 47.91.111.180 Dec 25 15:05:38 volumio ntpd[2020]: Soliciting pool server 5.32.10.107 Dec 25 15:05:39 volumio ntpd[2020]: Soliciting pool server 89.44.80.106 Dec 25 15:05:39 volumio ntpd[2020]: Soliciting pool server 89.44.80.106 Dec 25 15:05:39 volumio ntpd[2020]: Soliciting pool server 89.44.80.106 Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 25 15:05:40 volumio volumio[1218]: info: Adding plugin bluetooth to MyMusic Plugins Dec 25 15:05:40 volumio volumio[1218]: info: Adding plugin multiroom to MyMusic Plugins Dec 25 15:05:40 volumio volumio[1218]: info: Adding plugin metavolumio to MyMusic Plugins Dec 25 15:05:40 volumio volumio[1218]: info: Adding plugin cd_controller to MyMusic Plugins Dec 25 15:05:40 volumio volumio[1218]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 25 15:05:40 volumio volumio[1218]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 25 15:05:40 volumio volumio[1218]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 25 15:05:40 volumio volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 25 15:05:41 volumio volumio[1218]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 25 15:05:41 volumio volumio[1218]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 25 15:05:41 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:05:41 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:05:41 volumio volumio[1218]: info: Starting MyVolumio Remote Streaming Endpoints Dec 25 15:05:41 volumio volumio[1218]: info: MyVolumio login type: Token Dec 25 15:05:41 volumio volumio[1218]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 25 15:05:41 volumio volumio[1218]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 25 15:05:41 volumio volumio[1218]: info: Starting Streaming Service Transparent Proxy Dec 25 15:05:41 volumio volumio[1218]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 25 15:05:41 volumio volumio[1218]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 25 15:05:41 volumio volumio[1218]: info: Streaming services startup Dec 25 15:05:41 volumio volumio[1218]: info: Starting Streaming Daemon Dec 25 15:05:41 volumio sudo[2030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 25 15:05:41 volumio sudo[2030]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:41 volumio volumio[1218]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 25 15:05:41 volumio sudo[2030]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:41 volumio volumio[1218]: error: Cannot start Volumio Streaming Daemon Dec 25 15:05:41 volumio volumio[1218]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 25 15:05:41 volumio volumio[1218]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 25 15:05:41 volumio volumio[1218]: STREAMING PROXY: Starting server on port 3245 Dec 25 15:05:41 volumio volumio[1218]: Node JS runtime: 14 Dec 25 15:05:41 volumio volumio[1218]: error: MyVolumio Custom Token format not valid, refreshing it Dec 25 15:05:43 volumio volumio[1218]: info: MyVolumio login type: Token Dec 25 15:05:44 volumio volumio-time-update[836]: volumio-time-update-util: Date not found in response Dec 25 15:05:44 volumio volumio-time-update[836]: volumio-time-update-util: Retrying in 5 seconds... Dec 25 15:05:44 volumio volumio[1218]: info: Volumio Network Manager: Network status updated: 1 Dec 25 15:05:44 volumio volumio[1218]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 25 15:05:46 volumio volumio[1218]: info: MyVolumio token set successfully Dec 25 15:05:46 volumio volumio[1218]: info: MYVOLUMIO: Adding device Dec 25 15:05:46 volumio volumio[1218]: info: MYVOLUMIO: Evaluating Server Dec 25 15:05:47 volumio volumio[1218]: info: MyVolumio status changed Dec 25 15:05:47 volumio volumio[1218]: info: Streaming services startup Dec 25 15:05:47 volumio volumio[1218]: info: Starting Streaming Daemon Dec 25 15:05:47 volumio volumio[1218]: info: Removing browser output: myVolumio user plan is not superstar Dec 25 15:05:47 volumio volumio[1218]: info: Removing audio output: Dec 25 15:05:47 volumio volumio[1218]: info: Stoppping Tunnel 1 Dec 25 15:05:47 volumio sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 25 15:05:47 volumio sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:47 volumio sudo[2073]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:47 volumio volumio[1218]: error: Cannot start Volumio Streaming Daemon Dec 25 15:05:47 volumio volumio[1218]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 25 15:05:47 volumio volumio[1218]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 25 15:05:47 volumio sudo[2076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 25 15:05:47 volumio sudo[2076]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:47 volumio sudo[2076]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:47 volumio volumio[1218]: info: Remote SSH Stopped Dec 25 15:05:50 volumio volumio-time-update[836]: volumio-time-update-util: Fetching time from Volumio... Dec 25 15:05:50 volumio volumio-time-update[836]: volumio-time-update-util: Setting system time to: 2025-12-25 15:05:50 Dec 25 15:05:50 volumio sudo[2127]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-25 15:05:50 Dec 25 15:05:50 volumio sudo[2127]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 25 15:05:50 volumio dbus-daemon[841]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.11' (uid=0 pid=2128 comm="timedatectl set-time 2025-12-25 15:05:50 ") Dec 25 15:05:50 volumio systemd[1]: Starting Time & Date Service... Dec 25 15:05:52 volumio dbus-daemon[841]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 25 15:05:52 volumio systemd[1]: Started Time & Date Service. Dec 25 15:05:50 volumio systemd-timedated[2129]: Changed local time to Thu Dec 25 15:05:50 2025 Dec 25 15:05:50 volumio sudo[2127]: pam_unix(sudo:session): session closed for user root Dec 25 15:05:50 volumio volumio-time-update[836]: volumio-time-update-util: System time updated successfully. Dec 25 15:05:50 volumio systemd[1]: Started Volumio Time Update Utility. Dec 25 15:05:50 volumio systemd[1]: Reached target Multi-User System. Dec 25 15:05:50 volumio systemd[1]: Reached target Graphical Interface. Dec 25 15:05:50 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 25 15:05:50 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 25 15:05:50 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 25 15:05:50 volumio systemd[1]: Startup finished in 13.323s (firmware) + 2.472s (loader) + 5.600s (kernel) + 2min 3.315s (userspace) = 2min 24.713s. Dec 25 15:05:50 volumio volumio[1218]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Dec 25 15:05:50 volumio volumio[1218]: info: Setting Geolocation for MyVolumio to as1 Dec 25 15:05:50 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:05:50 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:05:50 volumio volumio[1218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 15:05:50 volumio volumio[1218]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 15:05:50 volumio volumio[1218]: Error: Unable to resolve or reject the same promise twice Dec 25 15:05:50 volumio volumio[1218]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 25 15:05:50 volumio volumio[1218]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Dec 25 15:05:50 volumio volumio[1218]: at Socket.emit (events.js:412:35) Dec 25 15:05:50 volumio volumio[1218]: at endReadableNT (internal/streams/readable.js:1333:12) Dec 25 15:05:50 volumio volumio[1218]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Dec 25 15:05:50 volumio volumio[1218]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 15:05:50 volumio sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-25 15:04 Dec 25 15:05:50 volumio sudo[2141]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:31:13 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="674d725e3f749f2a23785c9f94a28aa3"