-- Logs begin at Wed 2024-07-10 10:54:47 CEST, end at Wed 2024-07-10 10:58:13 CEST. -- Jul 10 10:57:14 volumio ntpd[903]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 10 10:57:34 volumio ntpd[903]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 10 10:57:54 volumio ntpd[903]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 10 10:58:00 volumio dhcpcd[798]: eth0: offered 192.168.1.22 from 192.168.1.1 Jul 10 10:58:01 volumio dhcpcd[798]: eth0: probing address 192.168.1.22/24 Jul 10 10:58:06 volumio dhcpcd[798]: eth0: leased 192.168.1.22 for 43200 seconds Jul 10 10:58:06 volumio dhcpcd[798]: eth0: adding route to 192.168.1.0/24 Jul 10 10:58:06 volumio dhcpcd[798]: eth0: changing default route via 192.168.1.1 Jul 10 10:58:06 volumio avahi-daemon[699]: Registering new address record for 192.168.1.22 on eth0.IPv4. Jul 10 10:58:06 volumio ntpd[903]: ntpd exiting on signal 15 (Terminated) Jul 10 10:58:06 volumio systemd[1]: Stopping Network Time Service... Jul 10 10:58:06 volumio systemd[1]: ntp.service: Succeeded. Jul 10 10:58:06 volumio systemd[1]: Stopped Network Time Service. Jul 10 10:58:06 volumio systemd[1]: Starting Network Time Service... Jul 10 10:58:06 volumio ntpd[1594]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 10 10:58:06 volumio ntpd[1594]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Jul 10 10:58:06 volumio systemd[1]: Started Network Time Service. Jul 10 10:58:06 volumio ntpd[1600]: proto: precision = 0.048 usec (-24) Jul 10 10:58:06 volumio ntpd[1600]: Listen and drop on 0 v6wildcard [::]:123 Jul 10 10:58:06 volumio ntpd[1600]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 10 10:58:06 volumio ntpd[1600]: Listen normally on 2 lo 127.0.0.1:123 Jul 10 10:58:06 volumio ntpd[1600]: Listen normally on 3 eth0 169.254.139.24:123 Jul 10 10:58:06 volumio ntpd[1600]: Listen normally on 4 eth0 192.168.1.22:123 Jul 10 10:58:06 volumio avahi-daemon[699]: Withdrawing address record for 169.254.139.24 on eth0. Jul 10 10:58:06 volumio avahi-daemon[699]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.139.24. Jul 10 10:58:06 volumio ntpd[1600]: Listening on routing socket on fd #21 for interface updates Jul 10 10:58:06 volumio dhcpcd[798]: eth0: deleting route to 169.254.0.0/16 Jul 10 10:58:06 volumio ntpd[1600]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 10 10:58:06 volumio ntpd[1600]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 10 10:58:06 volumio avahi-daemon[699]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.22. Jul 10 10:58:08 volumio ntpd[1600]: Deleting interface #3 eth0, 169.254.139.24#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 10 10:58:09 volumio volumio[920]: info: Adding plugin bluetooth to MyMusic Plugins Jul 10 10:58:09 volumio volumio[920]: info: Adding plugin multiroom to MyMusic Plugins Jul 10 10:58:09 volumio volumio[920]: info: Adding plugin metavolumio to MyMusic Plugins Jul 10 10:58:09 volumio volumio[920]: info: Adding plugin cd_controller to MyMusic Plugins Jul 10 10:58:09 volumio volumio[920]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 10 10:58:09 volumio volumio[920]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 10 10:58:09 volumio volumio[920]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 10 10:58:10 volumio volumio[920]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 10 10:58:10 volumio volumio[920]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 10 10:58:10 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 10:58:10 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 10:58:10 volumio volumio[920]: info: Starting MyVolumio Remote Streaming Endpoints Jul 10 10:58:10 volumio volumio[920]: info: MyVolumio login type: Token Jul 10 10:58:10 volumio volumio[920]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 10 10:58:10 volumio volumio[920]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 10 10:58:10 volumio volumio[920]: info: Starting Streaming Service Transparent Proxy Jul 10 10:58:10 volumio volumio[920]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 10 10:58:10 volumio volumio[920]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 10 10:58:10 volumio volumio[920]: info: Streaming services startup Jul 10 10:58:10 volumio volumio[920]: info: Starting Streaming Daemon Jul 10 10:58:10 volumio sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 10 10:58:10 volumio sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 10:58:10 volumio volumio[920]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 10 10:58:10 volumio sudo[1624]: pam_unix(sudo:session): session closed for user root Jul 10 10:58:10 volumio volumio[920]: error: Cannot start Volumio Streaming Daemon Jul 10 10:58:10 volumio volumio[920]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 10 10:58:10 volumio volumio[920]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 10 10:58:10 volumio volumio[920]: STREAMING PROXY: Starting server on port 3245 Jul 10 10:58:10 volumio volumio[920]: Node JS runtime: 14 Jul 10 10:58:10 volumio volumio[920]: error: MyVolumio Custom Token format not valid, refreshing it Jul 10 10:58:11 volumio volumio[920]: info: MyVolumio login type: Token Jul 10 10:58:12 volumio volumio[920]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 10 10:58:12 volumio volumio[920]: info: MyVolumio token set successfully Jul 10 10:58:12 volumio volumio[920]: info: MYVOLUMIO: Adding device Jul 10 10:58:12 volumio volumio[920]: info: MYVOLUMIO: Evaluating Server Jul 10 10:58:12 volumio volumio[920]: info: MyVolumio Plan changed: premium Jul 10 10:58:12 volumio volumio[920]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jul 10 10:58:12 volumio volumio[920]: info: Removing browser output: myVolumio user plan is not superstar Jul 10 10:58:12 volumio volumio[920]: info: Removing audio output: Jul 10 10:58:12 volumio volumio[920]: info: MYVOLUMIO: Adding device Jul 10 10:58:12 volumio volumio[920]: info: MYVOLUMIO: Evaluating Server Jul 10 10:58:12 volumio volumio[920]: info: Remote config written successfully Jul 10 10:58:12 volumio volumio[920]: info: Starting Tunnel 1 Jul 10 10:58:12 volumio volumio[920]: info: Starting Tunnel Connection Checker Jul 10 10:58:13 volumio volumio[920]: info: MYVolumio Device enabled Jul 10 10:58:13 volumio volumio[920]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jul 10 10:58:13 volumio volumio[920]: info: MyVolumio status changed Jul 10 10:58:13 volumio volumio[920]: info: Streaming services startup Jul 10 10:58:13 volumio volumio[920]: info: Starting Streaming Daemon Jul 10 10:58:13 volumio sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 10 10:58:13 volumio sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 10:58:13 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jul 10 10:58:13 volumio volumio[920]: error: [MyVolumio PluginManager] Cache data is invalid! Jul 10 10:58:13 volumio sudo[1663]: pam_unix(sudo:session): session closed for user root Jul 10 10:58:13 volumio volumio[920]: error: Cannot start Volumio Streaming Daemon Jul 10 10:58:13 volumio volumio[920]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 10 10:58:13 volumio volumio[920]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 10 10:58:13 volumio volumio[920]: info: Setting Geolocation for MyVolumio to eu7 Jul 10 10:58:13 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 10:58:13 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 10:58:13 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 10:58:11 volumio volumio[920]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Jul 10 10:58:11 volumio volumio[920]: info: Setting Geolocation for MyVolumio to eu6 Jul 10 10:58:11 volumio volumio[920]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 10:58:11 volumio volumio[920]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 10:58:11 volumio volumio[920]: Error: Unable to resolve or reject the same promise twice Jul 10 10:58:11 volumio volumio[920]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 10 10:58:11 volumio volumio[920]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Jul 10 10:58:11 volumio volumio[920]: at Socket.emit (events.js:412:35) Jul 10 10:58:11 volumio volumio[920]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 10 10:58:11 volumio volumio[920]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 10 10:58:11 volumio volumio[920]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 10:58:11 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 10:57 Jul 10 10:58:11 volumio sudo[1675]: 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="088bb4e41087c61f43ccdd4810f989f084679514" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="218f986668198768ef5630191ffb17431779722f" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 26 Jun 2024 04:04:53 PM CEST" VOLUMIO_VERSION="3.728" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dd6e0fe0707a2f8add652fcd92267d3d"