-- Logs begin at Fri 2024-10-04 23:23:32 UTC, end at Fri 2024-10-04 23:25:56 UTC. -- Oct 04 23:24:00 volumio volumio[987]: info: Discovery: adding 1b7fa1fd-ffd0-404b-8bbf-f3480c40718f Oct 04 23:24:00 volumio volumio[987]: info: Discovery: Found device Volumio Oct 04 23:24:00 volumio volumio[987]: info: CoreCommandRouter::volumioGetState Oct 04 23:24:00 volumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 23:24:05 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 04 23:24:07 volumio volumio[987]: error: MyVolumio Plugin failed to start in a timely fashion Oct 04 23:24:07 volumio volumio[987]: info: BOOT COMPLETED Oct 04 23:24:07 volumio volumio[987]: [Metrics] CommandRouter: 27s 687.20ms Oct 04 23:24:07 volumio volumio[987]: info: CoreCommandRouter::volumiosetStartupVolume Oct 04 23:24:07 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 04 23:24:07 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 23:24:07 volumio volumio[987]: info: CoreCommandRouter::Close All Modals sent Oct 04 23:24:07 volumio volumio[987]: info: CoreCommandRouter::Close All Modals sent Oct 04 23:24:08 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 04 23:24:08 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 04 23:24:08 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 04 23:24:10 volumio volumio[987]: info: Volumio called home Oct 04 23:24:14 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 04 23:24:14 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 23:24:14 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 04 23:24:57 volumio ntpd[964]: Soliciting pool server 192.168.1.1 Oct 04 23:25:02 volumio ntpd[964]: Soliciting pool server 192.168.1.1 Oct 04 23:25:07 volumio ntpd[964]: Soliciting pool server 192.168.1.1 Oct 04 23:25:12 volumio ntpd[964]: Soliciting pool server 192.168.1.1 Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 04 23:25:47 volumio volumio[987]: info: Adding plugin bluetooth to MyMusic Plugins Oct 04 23:25:47 volumio volumio[987]: info: Adding plugin multiroom to MyMusic Plugins Oct 04 23:25:47 volumio volumio[987]: info: Adding plugin metavolumio to MyMusic Plugins Oct 04 23:25:47 volumio volumio[987]: info: Adding plugin cd_controller to MyMusic Plugins Oct 04 23:25:47 volumio volumio[987]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 04 23:25:47 volumio volumio[987]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 04 23:25:47 volumio volumio[987]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 04 23:25:48 volumio volumio[987]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 04 23:25:48 volumio volumio[987]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 04 23:25:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 23:25:48 volumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 04 23:25:48 volumio volumio[987]: info: Starting MyVolumio Remote Streaming Endpoints Oct 04 23:25:49 volumio volumio[987]: info: MyVolumio login type: Token Oct 04 23:25:49 volumio volumio[987]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 04 23:25:49 volumio volumio[987]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 04 23:25:49 volumio ntpd[964]: Soliciting pool server 89.252.135.27 Oct 04 23:25:49 volumio ntpd[964]: Soliciting pool server 162.159.200.1 Oct 04 23:25:49 volumio volumio[987]: info: Starting Streaming Service Transparent Proxy Oct 04 23:25:50 volumio volumio[987]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 04 23:25:50 volumio volumio[987]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 04 23:25:50 volumio volumio[987]: info: Streaming services startup Oct 04 23:25:50 volumio volumio[987]: info: Starting Streaming Daemon Oct 04 23:25:50 volumio sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 04 23:25:50 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 23:25:50 volumio volumio[987]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 04 23:25:50 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Oct 04 23:25:50 volumio volumio[987]: error: Cannot start Volumio Streaming Daemon Oct 04 23:25:50 volumio volumio[987]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 04 23:25:50 volumio volumio[987]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 04 23:25:50 volumio ntpd[964]: Soliciting pool server 176.235.250.150 Oct 04 23:25:50 volumio volumio[987]: STREAMING PROXY: Starting server on port 3245 Oct 04 23:25:50 volumio volumio[987]: Node JS runtime: 14 Oct 04 23:25:50 volumio volumio[987]: error: MyVolumio Custom Token format not valid, refreshing it Oct 04 23:25:51 volumio ntpd[964]: Soliciting pool server 194.27.222.5 Oct 04 23:25:51 volumio ntpd[964]: Soliciting pool server 162.159.200.123 Oct 04 23:25:52 volumio ntpd[964]: Soliciting pool server 178.79.155.116 Oct 04 23:25:52 volumio ntpd[964]: Soliciting pool server 45.136.155.37 Oct 04 23:25:52 volumio ntpd[964]: Soliciting pool server 62.12.173.11 Oct 04 23:25:53 volumio ntpd[964]: Soliciting pool server 192.36.143.130 Oct 04 23:25:53 volumio ntpd[964]: Soliciting pool server 194.27.156.207 Oct 04 23:25:53 volumio volumio[987]: info: MyVolumio login type: Token Oct 04 23:25:54 volumio volumio[987]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 04 23:25:54 volumio ntpd[964]: Soliciting pool server 37.187.145.181 Oct 04 23:25:54 volumio ntpd[964]: Soliciting pool server 85.199.214.98 Oct 04 23:25:54 volumio volumio[987]: info: MyVolumio token set successfully Oct 04 23:25:54 volumio volumio[987]: info: MYVOLUMIO: Adding device Oct 04 23:25:54 volumio volumio[987]: info: MYVOLUMIO: Evaluating Server Oct 04 23:25:55 volumio volumio[987]: info: MyVolumio status changed Oct 04 23:25:55 volumio volumio[987]: info: Streaming services startup Oct 04 23:25:55 volumio volumio[987]: info: Starting Streaming Daemon Oct 04 23:25:55 volumio volumio[987]: info: Removing browser output: myVolumio user plan is not superstar Oct 04 23:25:55 volumio volumio[987]: info: Removing audio output: Oct 04 23:25:55 volumio volumio[987]: info: Stoppping Tunnel 1 Oct 04 23:25:55 volumio sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 04 23:25:55 volumio ntpd[964]: Soliciting pool server 101.44.222.53 Oct 04 23:25:55 volumio ntpd[964]: Soliciting pool server 2606:4700:f1::1 Oct 04 23:25:55 volumio sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 23:25:55 volumio sudo[1383]: pam_unix(sudo:session): session closed for user root Oct 04 23:25:55 volumio volumio[987]: error: Cannot start Volumio Streaming Daemon Oct 04 23:25:55 volumio volumio[987]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 04 23:25:55 volumio volumio[987]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 04 23:25:55 volumio sudo[1386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 04 23:25:55 volumio sudo[1386]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 23:25:55 volumio sudo[1386]: pam_unix(sudo:session): session closed for user root Oct 04 23:25:55 volumio volumio[987]: info: Remote SSH Stopped Oct 04 23:25:55 volumio ntpd[964]: receive: Unexpected origin timestamp 0xeaaaf804.2b1ee808 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xeaaaf803.ee4ebe06 Oct 04 23:25:55 volumio ntpd[964]: receive: Unexpected origin timestamp 0xeaaaf804.2b1ba401 does not match aorg 0000000000.00000000 from server@178.79.155.116 xmt 0xeaaaf803.f4942647 Oct 04 23:25:55 volumio ntpd[964]: receive: Unexpected origin timestamp 0xeaaaf804.2b1666cf does not match aorg 0000000000.00000000 from server@192.36.143.130 xmt 0xeaaaf803.f31ad84c Oct 04 23:25:56 volumio volumio[987]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Oct 04 23:25:56 volumio volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 23:25:56 volumio volumio[987]: Error: Unable to resolve or reject the same promise twice Oct 04 23:25:56 volumio volumio[987]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 04 23:25:56 volumio volumio[987]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Oct 04 23:25:56 volumio volumio[987]: at Socket.emit (events.js:412:35) Oct 04 23:25:56 volumio volumio[987]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 04 23:25:56 volumio volumio[987]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 04 23:25:56 volumio volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 23:25:56 volumio sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 23:24 Oct 04 23:25:56 volumio sudo[1397]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"