-- Logs begin at Sat 2024-11-23 13:52:20 CET, end at Sat 2024-11-23 13:59:42 CET. -- Nov 23 13:58:02 golf volumio-time-update[811]: volumio-time-update-util: Fetching time from Volumio... Nov 23 13:58:09 golf ntpd[985]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 23 13:58:22 golf volumio-time-update[811]: volumio-time-update-util: Date not found in response Nov 23 13:58:22 golf volumio-time-update[811]: volumio-time-update-util: Retrying in 5 seconds... Nov 23 13:58:27 golf volumio-time-update[811]: volumio-time-update-util: Fetching time from Volumio... Nov 23 13:58:29 golf ntpd[985]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 23 13:58:47 golf volumio-time-update[811]: volumio-time-update-util: Date not found in response Nov 23 13:58:47 golf volumio-time-update[811]: volumio-time-update-util: Retrying in 5 seconds... Nov 23 13:58:49 golf ntpd[985]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 23 13:58:52 golf volumio-time-update[811]: volumio-time-update-util: Fetching time from Volumio... Nov 23 13:59:09 golf ntpd[985]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 23 13:59:12 golf volumio-time-update[811]: volumio-time-update-util: Date not found in response Nov 23 13:59:12 golf volumio-time-update[811]: volumio-time-update-util: Retrying in 5 seconds... Nov 23 13:59:17 golf volumio-time-update[811]: volumio-time-update-util: Fetching time from Volumio... Nov 23 13:59:29 golf ntpd[985]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 23 13:59:34 golf dhcpcd[1079]: eth0: carrier acquired Nov 23 13:59:34 golf kernel: macb 1f00100000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Nov 23 13:59:34 golf dhcpcd[1079]: eth0: IAID 67:98:6b:be Nov 23 13:59:34 golf dhcpcd[1079]: eth0: adding address fe80::2e19:4d09:974e:4698 Nov 23 13:59:34 golf dhcpcd[1079]: ipv6_addaddr1: Permission denied Nov 23 13:59:34 golf ifplugd(eth0)[978]: Link beat detected. Nov 23 13:59:35 golf ifplugd(eth0)[978]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 23 13:59:35 golf dhcpcd[2544]: sending commands to master dhcpcd process Nov 23 13:59:35 golf ifplugd(eth0)[978]: client: sending commands to master dhcpcd process Nov 23 13:59:35 golf dhcpcd[1079]: control command: /sbin/dhcpcd eth0 Nov 23 13:59:35 golf dhcpcd[1079]: eth0: rebinding lease of 192.168.1.84 Nov 23 13:59:35 golf dhcpcd[1079]: eth0: probing address 192.168.1.84/24 Nov 23 13:59:35 golf ifplugd(eth0)[978]: Program executed successfully. Nov 23 13:59:35 golf dhcpcd[1079]: eth0: soliciting an IPv6 router Nov 23 13:59:37 golf volumio-time-update[811]: volumio-time-update-util: Date not found in response Nov 23 13:59:37 golf volumio-time-update[811]: volumio-time-update-util: Retrying in 5 seconds... Nov 23 13:59:39 golf dhcpcd[1079]: eth0: leased 192.168.1.84 for 864000 seconds Nov 23 13:59:39 golf avahi-daemon[816]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.84. Nov 23 13:59:39 golf avahi-daemon[816]: New relevant interface eth0.IPv4 for mDNS. Nov 23 13:59:39 golf dhcpcd[1079]: eth0: adding route to 192.168.1.0/24 Nov 23 13:59:39 golf dhcpcd[1079]: eth0: adding default route via 192.168.1.1 Nov 23 13:59:39 golf avahi-daemon[816]: Registering new address record for 192.168.1.84 on eth0.IPv4. Nov 23 13:59:39 golf dhcpcd[1079]: wlan0: deleting default route Nov 23 13:59:39 golf dnsmasq[1195]: reading /etc/resolv.conf Nov 23 13:59:39 golf dnsmasq[1195]: using nameserver 192.168.1.1#53 Nov 23 13:59:39 golf dnsmasq[1195]: using nameserver 208.67.222.222#53 Nov 23 13:59:39 golf dnsmasq[1195]: using nameserver 208.67.220.220#53 Nov 23 13:59:39 golf volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 13:59:39 golf volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 13:59:39 golf volumio[1205]: info: Discovery: Getting this device information Nov 23 13:59:39 golf volumio[1205]: info: CoreCommandRouter::volumioGetState Nov 23 13:59:39 golf volumio[1205]: info: CorePlayQueue::getTrack 0 Nov 23 13:59:39 golf volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 13:59:39 golf volumio[1205]: verbose: New Socket.io Connection to 192.168.1.84:3000 from 192.168.1.5 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Nov 23 13:59:39 golf volumio[1205]: info: CoreCommandRouter::volumioGetState Nov 23 13:59:39 golf volumio[1205]: info: CorePlayQueue::getTrack 0 Nov 23 13:59:39 golf volumio[1205]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 23 13:59:39 golf volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 23 13:59:39 golf ntpd[985]: Soliciting pool server 185.19.184.35 Nov 23 13:59:40 golf volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 13:59:40 golf volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 13:59:40 golf volumio[1205]: info: Discovery: Getting this device information Nov 23 13:59:40 golf volumio[1205]: info: CoreCommandRouter::volumioGetState Nov 23 13:59:40 golf volumio[1205]: info: CorePlayQueue::getTrack 0 Nov 23 13:59:40 golf volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 13:59:40 golf ntpd[985]: Soliciting pool server 212.45.144.3 Nov 23 13:59:40 golf ntpd[985]: Soliciting pool server 212.45.144.88 Nov 23 13:59:40 golf ntpd[985]: Soliciting pool server 193.204.114.232 Nov 23 13:59:40 golf ntpd[985]: Soliciting pool server 185.19.184.35 Nov 23 13:59:40 golf ntpd[985]: Soliciting pool server 212.45.144.3 Nov 23 13:59:40 golf volumio[1205]: info: Discovery: this is already registered, 2990aca6-9e94-4205-9b96-e8d25b25c24c Nov 23 13:59:40 golf volumio[1205]: info: Discovery: Found device GOLF Nov 23 13:59:40 golf volumio[1205]: info: CoreCommandRouter::volumioGetState Nov 23 13:59:40 golf volumio[1205]: info: CorePlayQueue::getTrack 0 Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 23 13:59:41 golf volumio[1205]: info: Adding plugin bluetooth to MyMusic Plugins Nov 23 13:59:41 golf volumio[1205]: info: Adding plugin multiroom to MyMusic Plugins Nov 23 13:59:41 golf volumio[1205]: info: Adding plugin metavolumio to MyMusic Plugins Nov 23 13:59:41 golf volumio[1205]: info: Adding plugin cd_controller to MyMusic Plugins Nov 23 13:59:41 golf volumio[1205]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 23 13:59:41 golf volumio[1205]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 23 13:59:41 golf ntpd[985]: Listen normally on 5 eth0 192.168.1.84:123 Nov 23 13:59:41 golf ntpd[985]: new interface(s) found: waking up resolver Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 23 13:59:41 golf volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:59:41 golf volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 13:59:41 golf volumio[1205]: info: Starting MyVolumio Remote Streaming Endpoints Nov 23 13:59:41 golf volumio[1205]: info: MyVolumio login type: Token Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 23 13:59:41 golf volumio[1205]: info: Starting Streaming Service Transparent Proxy Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 23 13:59:41 golf volumio[1205]: info: Streaming services startup Nov 23 13:59:41 golf volumio[1205]: info: Starting Streaming Daemon Nov 23 13:59:41 golf sudo[2600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 23 13:59:41 golf sudo[2600]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:59:41 golf volumio[1205]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 23 13:59:41 golf sudo[2600]: pam_unix(sudo:session): session closed for user root Nov 23 13:59:41 golf volumio[1205]: error: Cannot start Volumio Streaming Daemon Nov 23 13:59:41 golf volumio[1205]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 23 13:59:41 golf volumio[1205]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 23 13:59:41 golf volumio[1205]: STREAMING PROXY: Starting server on port 3245 Nov 23 13:59:41 golf volumio[1205]: Node JS runtime: 14 Nov 23 13:59:42 golf volumio[1205]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 23 13:59:42 golf volumio[1205]: info: Volumio Network Manager: Network status updated: 1 Nov 23 13:59:42 golf volumio[1205]: info: MyVolumio token set successfully Nov 23 13:59:42 golf volumio[1205]: info: MYVOLUMIO: Adding device Nov 23 13:59:42 golf volumio[1205]: info: MYVOLUMIO: Evaluating Server Nov 23 13:59:42 golf volumio-time-update[811]: volumio-time-update-util: Fetching time from Volumio... Nov 23 13:59:42 golf volumio-time-update[811]: volumio-time-update-util: Setting system time to: 2024-11-23 13:59:42 Nov 23 13:59:42 golf sudo[2636]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2024-11-23 13:59:42 Nov 23 13:59:42 golf sudo[2636]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:59:42 golf dbus-daemon[808]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=2638 comm="timedatectl set-time 2024-11-23 13:59:42 ") Nov 23 13:59:42 golf volumio[1205]: info: MyVolumio status changed Nov 23 13:59:42 golf volumio[1205]: info: Streaming services startup Nov 23 13:59:42 golf volumio[1205]: info: Starting Streaming Daemon Nov 23 13:59:42 golf volumio[1205]: info: Removing browser output: myVolumio user plan is not superstar Nov 23 13:59:42 golf volumio[1205]: info: Removing audio output: Nov 23 13:59:42 golf volumio[1205]: info: Stoppping Tunnel 1 Nov 23 13:59:42 golf sudo[2647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 23 13:59:42 golf sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:59:42 golf systemd[1]: Starting Time & Date Service... Nov 23 13:59:42 golf sudo[2650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 23 13:59:42 golf sudo[2650]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 13:59:42 golf sudo[2647]: pam_unix(sudo:session): session closed for user root Nov 23 13:59:42 golf sudo[2650]: pam_unix(sudo:session): session closed for user root Nov 23 13:59:42 golf volumio[1205]: error: Cannot start Volumio Streaming Daemon Nov 23 13:59:42 golf volumio[1205]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 23 13:59:42 golf volumio[1205]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 23 13:59:42 golf volumio[1205]: info: Remote SSH Stopped Nov 23 13:59:42 golf dbus-daemon[808]: [system] Successfully activated service 'org.freedesktop.timedate1' Nov 23 13:59:42 golf systemd[1]: Started Time & Date Service. Nov 23 13:59:42 golf systemd-timedated[2643]: Changed local time to Sat Nov 23 13:59:42 2024 Nov 23 13:59:42 golf sudo[2636]: pam_unix(sudo:session): session closed for user root Nov 23 13:59:42 golf volumio-time-update[811]: volumio-time-update-util: System time updated successfully. Nov 23 13:59:42 golf systemd[1]: Started Volumio Time Update Utility. Nov 23 13:59:42 golf systemd[1]: Reached target Multi-User System. Nov 23 13:59:42 golf systemd[1]: Reached target Graphical Interface. Nov 23 13:59:42 golf systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 23 13:59:42 golf systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 23 13:59:42 golf systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 23 13:59:42 golf systemd[1]: Startup finished in 11.794s (kernel) + 7min 23.081s (userspace) = 7min 34.876s. Nov 23 13:59:42 golf volumio[1205]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Nov 23 13:59:42 golf volumio[1205]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 13:59:42 golf volumio[1205]: Error: Unable to resolve or reject the same promise twice Nov 23 13:59:42 golf volumio[1205]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Nov 23 13:59:42 golf volumio[1205]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Nov 23 13:59:42 golf volumio[1205]: at Socket.emit (events.js:327:22) Nov 23 13:59:42 golf volumio[1205]: at endReadableNT (internal/streams/readable.js:1327:12) Nov 23 13:59:42 golf volumio[1205]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 23 13:59:42 golf volumio[1205]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 13:59:42 golf sudo[2664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 13:58 Nov 23 13:59:42 golf sudo[2664]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"