-- Logs begin at Wed 2025-03-05 20:28:44 CST, end at Wed 2025-03-05 21:58:39 CST. -- Mar 05 21:57:04 master-avr volumio-time-update[818]: volumio-time-update-util: Fetching time from Volumio... Mar 05 21:57:19 master-avr ntpd[1011]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 05 21:57:24 master-avr volumio-time-update[818]: volumio-time-update-util: Date not found in response Mar 05 21:57:24 master-avr volumio-time-update[818]: volumio-time-update-util: Retrying in 5 seconds... Mar 05 21:57:27 master-avr dhcpcd[1722]: eth0: soliciting a DHCP lease Mar 05 21:57:28 master-avr dhcpcd[1722]: eth0: offered 172.16.0.162 from 172.16.0.1 Mar 05 21:57:28 master-avr dhcpcd[1722]: eth0: NAK: from 172.16.0.1 Mar 05 21:57:28 master-avr dhcpcd[1722]: eth0: NAK: from 172.16.0.1 Mar 05 21:57:29 master-avr volumio-time-update[818]: volumio-time-update-util: Fetching time from Volumio... Mar 05 21:57:39 master-avr ntpd[1011]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 05 21:57:49 master-avr volumio-time-update[818]: volumio-time-update-util: Date not found in response Mar 05 21:57:49 master-avr volumio-time-update[818]: volumio-time-update-util: Retrying in 5 seconds... Mar 05 21:57:53 master-avr volumiologrotate[816]: ls: cannot access '/var/log/samba/log.wb-MASTER': No such file or directory Mar 05 21:57:53 master-avr volumiologrotate[816]: ls: cannot access 'AVR': No such file or directory Mar 05 21:57:54 master-avr volumio-time-update[818]: volumio-time-update-util: Fetching time from Volumio... Mar 05 21:57:59 master-avr ntpd[1011]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 05 21:58:14 master-avr volumio-time-update[818]: volumio-time-update-util: Date not found in response Mar 05 21:58:14 master-avr volumio-time-update[818]: volumio-time-update-util: Retrying in 5 seconds... Mar 05 21:58:19 master-avr ntpd[1011]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 05 21:58:19 master-avr volumio-time-update[818]: volumio-time-update-util: Fetching time from Volumio... Mar 05 21:58:28 master-avr dhcpcd[1722]: eth0: soliciting a DHCP lease Mar 05 21:58:28 master-avr dhcpcd[1722]: eth0: offered 172.16.0.153 from 172.16.0.1 Mar 05 21:58:28 master-avr dhcpcd[1722]: eth0: probing address 172.16.0.153/24 Mar 05 21:58:33 master-avr dhcpcd[1722]: eth0: leased 172.16.0.153 for 7200 seconds Mar 05 21:58:33 master-avr dhcpcd[1722]: eth0: adding route to 172.16.0.0/24 Mar 05 21:58:33 master-avr dhcpcd[1722]: eth0: changing default route via 172.16.0.1 Mar 05 21:58:33 master-avr avahi-daemon[820]: Registering new address record for 172.16.0.153 on eth0.IPv4. Mar 05 21:58:33 master-avr dnsmasq[1310]: reading /etc/resolv.conf Mar 05 21:58:33 master-avr dnsmasq[1310]: using nameserver 192.168.1.254#53 Mar 05 21:58:33 master-avr dnsmasq[1310]: using nameserver 172.16.0.1#53 Mar 05 21:58:33 master-avr dnsmasq[1310]: using nameserver 208.67.222.222#53 Mar 05 21:58:33 master-avr dnsmasq[1310]: using nameserver 208.67.220.220#53 Mar 05 21:58:33 master-avr ntpd[1011]: ntpd exiting on signal 15 (Terminated) Mar 05 21:58:33 master-avr systemd[1]: Stopping Network Time Service... Mar 05 21:58:33 master-avr systemd[1]: ntp.service: Succeeded. Mar 05 21:58:33 master-avr systemd[1]: Stopped Network Time Service. Mar 05 21:58:33 master-avr systemd[1]: Starting Network Time Service... Mar 05 21:58:33 master-avr ntpd[17632]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 05 21:58:33 master-avr ntpd[17632]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 05 21:58:33 master-avr systemd[1]: Started Network Time Service. Mar 05 21:58:33 master-avr ntpd[17639]: proto: precision = 0.068 usec (-24) Mar 05 21:58:33 master-avr ntpd[17639]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 05 21:58:33 master-avr ntpd[17639]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 05 21:58:33 master-avr ntpd[17639]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 800 days ago Mar 05 21:58:33 master-avr dhcpcd[1722]: eth0: deleting route to 169.254.0.0/16 Mar 05 21:58:33 master-avr avahi-daemon[820]: Withdrawing address record for 169.254.43.9 on eth0. Mar 05 21:58:33 master-avr avahi-daemon[820]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.43.9. Mar 05 21:58:33 master-avr avahi-daemon[820]: Joining mDNS multicast group on interface eth0.IPv4 with address 172.16.0.153. Mar 05 21:58:33 master-avr ntpd[17639]: Listen and drop on 0 v6wildcard [::]:123 Mar 05 21:58:33 master-avr ntpd[17639]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 05 21:58:33 master-avr ntpd[17639]: Listen normally on 2 lo 127.0.0.1:123 Mar 05 21:58:33 master-avr ntpd[17639]: Listen normally on 3 eth0 172.16.0.153:123 Mar 05 21:58:33 master-avr ntpd[17639]: Listen normally on 4 wlan0 192.168.211.1:123 Mar 05 21:58:33 master-avr ntpd[17639]: Listen normally on 5 wlan0 169.254.157.213:123 Mar 05 21:58:33 master-avr ntpd[17639]: Listening on routing socket on fd #22 for interface updates Mar 05 21:58:33 master-avr ntpd[17639]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Mar 05 21:58:33 master-avr ntpd[17639]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Mar 05 21:58:34 master-avr volumio-time-update[818]: volumio-time-update-util: Date not found in response Mar 05 21:58:34 master-avr volumio-time-update[818]: volumio-time-update-util: Retrying in 5 seconds... Mar 05 21:58:34 master-avr ntpd[17639]: Soliciting pool server 168.61.215.74 Mar 05 21:58:35 master-avr ntpd[17639]: Soliciting pool server 23.168.24.210 Mar 05 21:58:35 master-avr ntpd[17639]: Soliciting pool server 15.204.87.223 Mar 05 21:58:36 master-avr ntpd[17639]: Soliciting pool server 104.131.155.175 Mar 05 21:58:36 master-avr ntpd[17639]: Soliciting pool server 65.19.142.137 Mar 05 21:58:36 master-avr ntpd[17639]: Soliciting pool server 198.199.14.101 Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 05 21:58:37 master-avr volumio[1258]: info: Adding plugin bluetooth to MyMusic Plugins Mar 05 21:58:37 master-avr volumio[1258]: info: Adding plugin multiroom to MyMusic Plugins Mar 05 21:58:37 master-avr volumio[1258]: info: Adding plugin metavolumio to MyMusic Plugins Mar 05 21:58:37 master-avr volumio[1258]: info: Adding plugin cd_controller to MyMusic Plugins Mar 05 21:58:37 master-avr volumio[1258]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 05 21:58:37 master-avr volumio[1258]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 05 21:58:37 master-avr volumio[1258]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 05 21:58:37 master-avr ntpd[17639]: Soliciting pool server 45.83.234.123 Mar 05 21:58:37 master-avr ntpd[17639]: Soliciting pool server 166.88.142.52 Mar 05 21:58:37 master-avr ntpd[17639]: Soliciting pool server 71.123.46.185 Mar 05 21:58:37 master-avr ntpd[17639]: Soliciting pool server 149.28.200.179 Mar 05 21:58:38 master-avr volumio[1258]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 05 21:58:38 master-avr volumio[1258]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 05 21:58:38 master-avr volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 21:58:38 master-avr volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 21:58:38 master-avr volumio[1258]: info: Starting MyVolumio Remote Streaming Endpoints Mar 05 21:58:38 master-avr volumio[1258]: info: MyVolumio login type: Token Mar 05 21:58:38 master-avr volumio[1258]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 05 21:58:38 master-avr volumio[1258]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 05 21:58:38 master-avr ntpd[17639]: Soliciting pool server 172.232.28.194 Mar 05 21:58:38 master-avr ntpd[17639]: Soliciting pool server 23.111.186.186 Mar 05 21:58:38 master-avr ntpd[17639]: Soliciting pool server 74.50.48.56 Mar 05 21:58:38 master-avr volumio[1258]: info: Starting Streaming Service Transparent Proxy Mar 05 21:58:38 master-avr volumio[1258]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 05 21:58:38 master-avr volumio[1258]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 05 21:58:38 master-avr volumio[1258]: info: Streaming services startup Mar 05 21:58:38 master-avr volumio[1258]: info: Starting Streaming Daemon Mar 05 21:58:38 master-avr sudo[17688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 05 21:58:38 master-avr sudo[17688]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 21:58:38 master-avr sudo[17688]: pam_unix(sudo:session): session closed for user root Mar 05 21:58:38 master-avr volumio[1258]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 05 21:58:38 master-avr volumio[1258]: error: Cannot start Volumio Streaming Daemon Mar 05 21:58:38 master-avr volumio[1258]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 05 21:58:38 master-avr volumio[1258]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 05 21:58:38 master-avr volumio[1258]: error: MyVolumio Custom Token format not valid, refreshing it Mar 05 21:58:39 master-avr volumio[1258]: STREAMING PROXY: Starting server on port 3245 Mar 05 21:58:39 master-avr volumio[1258]: Node JS runtime: 14 Mar 05 21:58:39 master-avr volumio[1258]: info: MyVolumio login type: Token Mar 05 21:58:39 master-avr volumio-time-update[818]: volumio-time-update-util: Fetching time from Volumio... Mar 05 21:58:39 master-avr volumio[1258]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 05 21:58:39 master-avr ntpd[17639]: Soliciting pool server 147.135.15.159 Mar 05 21:58:39 master-avr ntpd[17639]: Soliciting pool server 23.150.41.123 Mar 05 21:58:39 master-avr volumio-time-update[818]: volumio-time-update-util: Setting system time to: 2025-03-05 21:58:39 Mar 05 21:58:39 master-avr sudo[17701]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-03-05 21:58:39 Mar 05 21:58:39 master-avr sudo[17701]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 21:58:39 master-avr volumio[1258]: info: MyVolumio token set successfully Mar 05 21:58:39 master-avr volumio[1258]: info: MYVOLUMIO: Adding device Mar 05 21:58:39 master-avr volumio[1258]: info: MYVOLUMIO: Evaluating Server Mar 05 21:58:39 master-avr dbus-daemon[823]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.15' (uid=0 pid=17702 comm="timedatectl set-time 2025-03-05 21:58:39 ") Mar 05 21:58:39 master-avr systemd[1]: Starting Time & Date Service... Mar 05 21:58:39 master-avr dbus-daemon[823]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 05 21:58:39 master-avr systemd[1]: Started Time & Date Service. Mar 05 21:58:39 master-avr systemd-timedated[17703]: Changed local time to Wed Mar 5 21:58:39 2025 Mar 05 21:58:39 master-avr sudo[17701]: pam_unix(sudo:session): session closed for user root Mar 05 21:58:39 master-avr volumio-time-update[818]: volumio-time-update-util: System time updated successfully. Mar 05 21:58:39 master-avr systemd[1]: Started Volumio Time Update Utility. Mar 05 21:58:39 master-avr systemd[1]: Reached target Multi-User System. Mar 05 21:58:39 master-avr systemd[1]: Reached target Graphical Interface. Mar 05 21:58:39 master-avr systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 05 21:58:39 master-avr systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 05 21:58:39 master-avr systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 05 21:58:39 master-avr systemd[1]: Startup finished in 11.716s (firmware) + 2.265s (loader) + 8.169s (kernel) + 1h 29min 55.690s (userspace) = 1h 30min 17.842s. Mar 05 21:58:39 master-avr volumio[1258]: info: MyVolumio status changed Mar 05 21:58:39 master-avr volumio[1258]: info: Streaming services startup Mar 05 21:58:39 master-avr volumio[1258]: info: Starting Streaming Daemon Mar 05 21:58:39 master-avr volumio[1258]: info: Removing browser output: myVolumio user plan is not superstar Mar 05 21:58:39 master-avr volumio[1258]: info: Removing audio output: Mar 05 21:58:39 master-avr volumio[1258]: info: Stoppping Tunnel 1 Mar 05 21:58:39 master-avr sudo[17722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 05 21:58:39 master-avr sudo[17722]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 21:58:39 master-avr volumio[1258]: error: Failed to ping endpoint eu3.myvolumio.org : unknown error Mar 05 21:58:39 master-avr volumio[1258]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 21:58:39 master-avr sudo[17725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 05 21:58:39 master-avr sudo[17725]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 21:58:39 master-avr sudo[17722]: pam_unix(sudo:session): session closed for user root Mar 05 21:58:39 master-avr volumio[1258]: Error: Unable to resolve or reject the same promise twice Mar 05 21:58:39 master-avr volumio[1258]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 05 21:58:39 master-avr volumio[1258]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Mar 05 21:58:39 master-avr volumio[1258]: at Socket.emit (events.js:412:35) Mar 05 21:58:39 master-avr volumio[1258]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 05 21:58:39 master-avr volumio[1258]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 05 21:58:39 master-avr volumio[1258]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 21:58:39 master-avr sudo[17725]: pam_unix(sudo:session): session closed for user root Mar 05 21:58:39 master-avr sudo[17736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-05 21:57 Mar 05 21:58:39 master-avr sudo[17736]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"