-- Logs begin at Tue 2024-10-22 17:51:07 CEST, end at Tue 2024-10-22 17:53:29 CEST. -- Oct 22 17:52:00 volumiomof volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 17:52:00 volumiomof volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:52:00 volumiomof volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 22 17:52:09 volumiomof volumio[925]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.757&uuid=6bf5df050d7b330d6f716698b9222d12" http://updates.volumio.org/downloader-v1/track-device Oct 22 17:52:09 volumiomof volumio[925]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 22 17:52:09 volumiomof volumio[925]: Dload Upload Total Spent Left Speed Oct 22 17:52:09 volumiomof volumio[925]: [2.2K blob data] Oct 22 17:52:09 volumiomof volumio[925]: retrying in 5 seconds, trial 0 Oct 22 17:52:09 volumiomof volumio[925]: info: Volumio Calling Home Oct 22 17:52:46 volumiomof ntpd[870]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 22 17:52:47 volumiomof volumio[925]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.757&uuid=6bf5df050d7b330d6f716698b9222d12" http://updates.volumio.org/downloader-v1/track-device Oct 22 17:52:47 volumiomof volumio[925]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 22 17:52:47 volumiomof volumio[925]: Dload Upload Total Spent Left Speed Oct 22 17:52:47 volumiomof volumio[925]: [2.2K blob data] Oct 22 17:52:47 volumiomof volumio[925]: retrying in 5 seconds, trial 1 Oct 22 17:52:47 volumiomof volumio[925]: info: Volumio Calling Home Oct 22 17:52:55 volumiomof volumio[925]: info: Discovery: adding 64637dbe-0307-4032-8cea-fbdf82cf0b1c Oct 22 17:52:55 volumiomof volumio[925]: info: Discovery: Found device VolumioMOF Oct 22 17:52:55 volumiomof volumio[925]: info: CoreCommandRouter::volumioGetState Oct 22 17:52:55 volumiomof volumio[925]: info: CorePlayQueue::getTrack 0 Oct 22 17:53:14 volumiomof ntpd[870]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 22 17:53:22 volumiomof ntpd[870]: Soliciting pool server 217.160.115.110 Oct 22 17:53:22 volumiomof ntpd[870]: Soliciting pool server 185.198.109.227 Oct 22 17:53:23 volumiomof volumio[925]: (node:925) UnhandledPromiseRejectionWarning: FetchError: request to https://www.youtube.com/sw.js_data failed, reason: getaddrinfo ENOTFOUND www.youtube.com Oct 22 17:53:23 volumiomof volumio[925]: at ClientRequest. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:8098:18) Oct 22 17:53:23 volumiomof volumio[925]: at ClientRequest.emit (events.js:400:28) Oct 22 17:53:23 volumiomof volumio[925]: at TLSSocket.socketErrorListener (_http_client.js:475:9) Oct 22 17:53:23 volumiomof volumio[925]: at TLSSocket.emit (events.js:400:28) Oct 22 17:53:23 volumiomof volumio[925]: at emitErrorNT (internal/streams/destroy.js:106:8) Oct 22 17:53:23 volumiomof volumio[925]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Oct 22 17:53:23 volumiomof volumio[925]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 22 17:53:23 volumiomof volumio[925]: (Use `node --trace-warnings ...` to show where the warning was created) Oct 22 17:53:23 volumiomof volumio[925]: (node:925) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Oct 22 17:53:23 volumiomof volumio[925]: (node:925) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Oct 22 17:53:23 volumiomof volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 22 17:53:23 volumiomof volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 22 17:53:23 volumiomof volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 22 17:53:23 volumiomof volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 22 17:53:23 volumiomof ntpd[870]: Soliciting pool server 193.149.0.217 Oct 22 17:53:23 volumiomof ntpd[870]: Soliciting pool server 94.143.139.219 Oct 22 17:53:24 volumiomof ntpd[870]: Soliciting pool server 208.85.20.220 Oct 22 17:53:24 volumiomof ntpd[870]: Soliciting pool server 162.159.200.1 Oct 22 17:53:24 volumiomof ntpd[870]: Soliciting pool server 89.140.186.3 Oct 22 17:53:25 volumiomof volumio[925]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.757&uuid=6bf5df050d7b330d6f716698b9222d12" http://updates.volumio.org/downloader-v1/track-device Oct 22 17:53:25 volumiomof volumio[925]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 22 17:53:25 volumiomof volumio[925]: Dload Upload Total Spent Left Speed Oct 22 17:53:25 volumiomof volumio[925]: [2.2K blob data] Oct 22 17:53:25 volumiomof volumio[925]: retrying in 5 seconds, trial 2 Oct 22 17:53:25 volumiomof volumio[925]: info: Volumio Calling Home Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 22 17:53:25 volumiomof volumio[925]: info: Adding plugin bluetooth to MyMusic Plugins Oct 22 17:53:25 volumiomof volumio[925]: info: Adding plugin multiroom to MyMusic Plugins Oct 22 17:53:25 volumiomof volumio[925]: info: Adding plugin metavolumio to MyMusic Plugins Oct 22 17:53:25 volumiomof volumio[925]: info: Adding plugin cd_controller to MyMusic Plugins Oct 22 17:53:25 volumiomof volumio[925]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 22 17:53:25 volumiomof volumio[925]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 22 17:53:25 volumiomof ntpd[870]: Soliciting pool server 178.215.228.24 Oct 22 17:53:25 volumiomof ntpd[870]: Soliciting pool server 193.149.0.221 Oct 22 17:53:25 volumiomof ntpd[870]: Soliciting pool server 158.227.98.15 Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 22 17:53:25 volumiomof volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:53:25 volumiomof volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:53:25 volumiomof volumio[925]: info: Starting MyVolumio Remote Streaming Endpoints Oct 22 17:53:25 volumiomof volumio[925]: info: MyVolumio login type: Token Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 22 17:53:25 volumiomof volumio[925]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 22 17:53:26 volumiomof volumio[925]: info: Starting Streaming Service Transparent Proxy Oct 22 17:53:26 volumiomof volumio[925]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 22 17:53:26 volumiomof volumio[925]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 22 17:53:26 volumiomof volumio[925]: info: Streaming services startup Oct 22 17:53:26 volumiomof volumio[925]: info: Starting Streaming Daemon Oct 22 17:53:26 volumiomof sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 22 17:53:26 volumiomof sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:53:26 volumiomof sudo[1744]: pam_unix(sudo:session): session closed for user root Oct 22 17:53:26 volumiomof volumio[925]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 22 17:53:26 volumiomof volumio[925]: error: Cannot start Volumio Streaming Daemon Oct 22 17:53:26 volumiomof volumio[925]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 22 17:53:26 volumiomof volumio[925]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 22 17:53:26 volumiomof volumio[925]: info: Volumio called home Oct 22 17:53:26 volumiomof ntpd[870]: Soliciting pool server 194.0.5.123 Oct 22 17:53:26 volumiomof volumio[925]: STREAMING PROXY: Starting server on port 3245 Oct 22 17:53:26 volumiomof volumio[925]: Node JS runtime: 14 Oct 22 17:53:26 volumiomof volumio[925]: error: MyVolumio Custom Token format not valid, refreshing it Oct 22 17:53:27 volumiomof ntpd[870]: Soliciting pool server 5.250.191.170 Oct 22 17:53:27 volumiomof volumio[925]: info: MyVolumio login type: Token Oct 22 17:53:27 volumiomof ntpd[870]: Soliciting pool server 217.71.200.210 Oct 22 17:53:28 volumiomof volumio[925]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 22 17:53:28 volumiomof ntpd[870]: Soliciting pool server 82.223.203.159 Oct 22 17:53:29 volumiomof volumio[925]: info: MyVolumio token set successfully Oct 22 17:53:29 volumiomof volumio[925]: info: MYVOLUMIO: Adding device Oct 22 17:53:29 volumiomof volumio[925]: info: MYVOLUMIO: Evaluating Server Oct 22 17:53:29 volumiomof volumio[925]: info: MyVolumio status changed Oct 22 17:53:29 volumiomof volumio[925]: info: Streaming services startup Oct 22 17:53:29 volumiomof volumio[925]: info: Starting Streaming Daemon Oct 22 17:53:29 volumiomof volumio[925]: info: Removing browser output: myVolumio user plan is not superstar Oct 22 17:53:29 volumiomof volumio[925]: info: Removing audio output: Oct 22 17:53:29 volumiomof volumio[925]: info: Stoppping Tunnel 1 Oct 22 17:53:29 volumiomof sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 22 17:53:29 volumiomof sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:53:29 volumiomof sudo[1772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 22 17:53:29 volumiomof sudo[1772]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 17:53:29 volumiomof sudo[1769]: pam_unix(sudo:session): session closed for user root Oct 22 17:53:29 volumiomof volumio[925]: error: Cannot start Volumio Streaming Daemon Oct 22 17:53:29 volumiomof volumio[925]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 22 17:53:29 volumiomof volumio[925]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 22 17:53:29 volumiomof sudo[1772]: pam_unix(sudo:session): session closed for user root Oct 22 17:53:29 volumiomof volumio[925]: info: Remote SSH Stopped Oct 22 17:53:29 volumiomof ntpd[870]: Soliciting pool server 162.159.200.123 Oct 22 17:53:29 volumiomof ntpd[870]: receive: Unexpected origin timestamp 0xeac248f9.a8259f5d does not match aorg 0000000000.00000000 from server@94.143.139.219 xmt 0xeac248f9.3709f2c8 Oct 22 17:53:29 volumiomof ntpd[870]: receive: Unexpected origin timestamp 0xeac248f9.a8232e57 does not match aorg 0000000000.00000000 from server@158.227.98.15 xmt 0xeac248f9.374ee1cb Oct 22 17:53:29 volumiomof ntpd[870]: receive: Unexpected origin timestamp 0xeac248f9.a8220d34 does not match aorg 0000000000.00000000 from server@217.71.200.210 xmt 0xeac248f9.36d1fce3 Oct 22 17:53:29 volumiomof ntpd[870]: receive: Unexpected origin timestamp 0xeac248f9.a8240711 does not match aorg 0000000000.00000000 from server@89.140.186.3 xmt 0xeac248f9.382b20dd Oct 22 17:53:29 volumiomof volumio[925]: error: Failed to ping endpoint au1.myvolumio.org : unknown error Oct 22 17:53:29 volumiomof volumio[925]: info: Setting Geolocation for MyVolumio to eu3 Oct 22 17:53:29 volumiomof volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:53:29 volumiomof volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:53:29 volumiomof volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 17:53:29 volumiomof volumio[925]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 17:53:29 volumiomof volumio[925]: Error: Unable to resolve or reject the same promise twice Oct 22 17:53:29 volumiomof volumio[925]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 22 17:53:29 volumiomof volumio[925]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Oct 22 17:53:29 volumiomof volumio[925]: at Socket.emit (events.js:412:35) Oct 22 17:53:29 volumiomof volumio[925]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 22 17:53:29 volumiomof volumio[925]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 22 17:53:29 volumiomof volumio[925]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 17:53:29 volumiomof sudo[1784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-22 17:52 Oct 22 17:53:29 volumiomof sudo[1784]: 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"