-- Logs begin at Fri 2025-02-21 08:31:09 CET, end at Fri 2025-02-21 08:33:26 CET. -- Feb 21 08:32:00 volumio-pc volumio-time-update[630]: volumio-time-update-util: Date not found in response Feb 21 08:32:00 volumio-pc volumio-time-update[630]: volumio-time-update-util: Retrying in 5 seconds... Feb 21 08:32:02 volumio-pc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 21 08:32:02 volumio-pc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 21 08:32:02 volumio-pc systemd[1]: Stopped go-librespot Daemon. Feb 21 08:32:02 volumio-pc systemd[1]: Started go-librespot Daemon. Feb 21 08:32:02 volumio-pc go-librespot[2000]: Librespot-go daemon starting... Feb 21 08:32:02 volumio-pc go-librespot[2000]: time="2025-02-21T08:32:02+01:00" level=info msg="generated new device id: 68744851e3dccb3ccb743bbc3faecbdc99588398" Feb 21 08:32:02 volumio-pc go-librespot[2000]: time="2025-02-21T08:32:02+01:00" level=debug msg="stored credentials found for 1198621021" Feb 21 08:32:05 volumio-pc volumio-time-update[630]: volumio-time-update-util: Fetching time from Volumio... Feb 21 08:32:10 volumio-pc volumiologrotate[649]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 21 08:32:10 volumio-pc volumiologrotate[649]: ls: cannot access 'PC': No such file or directory Feb 21 08:32:17 volumio-pc volumio[940]: info: Discovery: this is already registered, 6f335b09-9e9b-45de-a994-67772c61b4f4 Feb 21 08:32:17 volumio-pc volumio[940]: info: Discovery: Found device Volumio AMP Feb 21 08:32:17 volumio-pc volumio[940]: info: Discovery: Connecting to remote: 192.168.18.80 Feb 21 08:32:17 volumio-pc go-librespot[2000]: time="2025-02-21T08:32:17+01:00" level=debug msg="new websocket client" Feb 21 08:32:17 volumio-pc volumio[940]: info: Connection to go-librespot Websocket established Feb 21 08:32:18 volumio-pc volumio[940]: info: Discovery: this is already registered, cefc5e59-57b6-49fa-9acb-52f42f7fd6ac Feb 21 08:32:18 volumio-pc volumio[940]: info: Discovery: Found device Volumio PC Feb 21 08:32:18 volumio-pc volumio[940]: info: CoreCommandRouter::volumioGetState Feb 21 08:32:18 volumio-pc volumio[940]: info: CorePlayQueue::getTrack 0 Feb 21 08:32:20 volumio-pc volumio[940]: info: Getting Spotify volume Feb 21 08:32:22 volumio-pc go-librespot[2000]: time="2025-02-21T08:32:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 21 08:32:22 volumio-pc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 08:32:22 volumio-pc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 08:32:22 volumio-pc volumio[940]: (node:940) UnhandledPromiseRejectionWarning: Error: socket hang up Feb 21 08:32:22 volumio-pc volumio[940]: at connResetException (internal/errors.js:639:14) Feb 21 08:32:22 volumio-pc volumio[940]: at Socket.socketOnEnd (_http_client.js:499:23) Feb 21 08:32:22 volumio-pc volumio[940]: at Socket.emit (events.js:412:35) Feb 21 08:32:22 volumio-pc volumio[940]: at endReadableNT (internal/streams/readable.js:1333:12) Feb 21 08:32:22 volumio-pc volumio[940]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Feb 21 08:32:22 volumio-pc volumio[940]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 21 08:32:22 volumio-pc volumio[940]: (node:940) 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) Feb 21 08:32:22 volumio-pc volumio[940]: (node:940) [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. Feb 21 08:32:22 volumio-pc volumio[940]: info: Connection to go-librespot Websocket closed Feb 21 08:32:25 volumio-pc volumio[940]: info: Initializing connection to go-librespot Websocket Feb 21 08:32:25 volumio-pc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 21 08:32:25 volumio-pc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 21 08:32:25 volumio-pc systemd[1]: Stopped go-librespot Daemon. Feb 21 08:32:25 volumio-pc systemd[1]: Started go-librespot Daemon. Feb 21 08:32:25 volumio-pc go-librespot[2113]: Librespot-go daemon starting... Feb 21 08:32:25 volumio-pc go-librespot[2113]: time="2025-02-21T08:32:25+01:00" level=info msg="generated new device id: 0248b0ce5b4965da63ac99ad30340896cf066e48" Feb 21 08:32:25 volumio-pc go-librespot[2113]: time="2025-02-21T08:32:25+01:00" level=debug msg="stored credentials found for 1198621021" Feb 21 08:32:25 volumio-pc volumio-time-update[630]: volumio-time-update-util: Date not found in response Feb 21 08:32:25 volumio-pc volumio-time-update[630]: volumio-time-update-util: Retrying in 5 seconds... Feb 21 08:32:30 volumio-pc volumio-time-update[630]: volumio-time-update-util: Fetching time from Volumio... Feb 21 08:32:38 volumio-pc ntpd[897]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 21 08:32:45 volumio-pc go-librespot[2113]: time="2025-02-21T08:32:45+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 21 08:32:45 volumio-pc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 08:32:45 volumio-pc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 08:32:48 volumio-pc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 21 08:32:48 volumio-pc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 21 08:32:48 volumio-pc systemd[1]: Stopped go-librespot Daemon. Feb 21 08:32:48 volumio-pc systemd[1]: Started go-librespot Daemon. Feb 21 08:32:48 volumio-pc go-librespot[2159]: Librespot-go daemon starting... Feb 21 08:32:48 volumio-pc go-librespot[2159]: time="2025-02-21T08:32:48+01:00" level=info msg="generated new device id: 4c251afab9c26a9db591d86d58db79a2bc1deab9" Feb 21 08:32:48 volumio-pc go-librespot[2159]: time="2025-02-21T08:32:48+01:00" level=debug msg="stored credentials found for 1198621021" Feb 21 08:32:50 volumio-pc volumio-time-update[630]: volumio-time-update-util: Date not found in response Feb 21 08:32:50 volumio-pc volumio-time-update[630]: volumio-time-update-util: Retrying in 5 seconds... Feb 21 08:32:51 volumio-pc volumio[940]: info: Discovery: Connected to remote: 169.254.238.17 Feb 21 08:32:55 volumio-pc volumio-time-update[630]: volumio-time-update-util: Fetching time from Volumio... Feb 21 08:32:58 volumio-pc ntpd[897]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 21 08:32:58 volumio-pc volumio[940]: info: Discovery: this is already registered, 6f335b09-9e9b-45de-a994-67772c61b4f4 Feb 21 08:32:58 volumio-pc volumio[940]: info: Discovery: Found device Volumio AMP Feb 21 08:32:58 volumio-pc volumio[940]: info: Discovery: Connecting to remote: 192.168.18.80 Feb 21 08:32:58 volumio-pc volumio[940]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 21 08:33:08 volumio-pc go-librespot[2159]: time="2025-02-21T08:33:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 21 08:33:08 volumio-pc systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 21 08:33:08 volumio-pc systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 21 08:33:10 volumio-pc volumiologrotate[649]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 21 08:33:10 volumio-pc volumiologrotate[649]: ls: cannot access 'PC': No such file or directory Feb 21 08:33:11 volumio-pc systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 21 08:33:11 volumio-pc systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 21 08:33:11 volumio-pc systemd[1]: Stopped go-librespot Daemon. Feb 21 08:33:11 volumio-pc systemd[1]: Started go-librespot Daemon. Feb 21 08:33:11 volumio-pc go-librespot[2254]: Librespot-go daemon starting... Feb 21 08:33:11 volumio-pc go-librespot[2254]: time="2025-02-21T08:33:11+01:00" level=info msg="generated new device id: b983ce41290328c809182d1e2383422605f27b05" Feb 21 08:33:11 volumio-pc go-librespot[2254]: time="2025-02-21T08:33:11+01:00" level=debug msg="stored credentials found for 1198621021" Feb 21 08:33:15 volumio-pc volumio-time-update[630]: volumio-time-update-util: Date not found in response Feb 21 08:33:15 volumio-pc volumio-time-update[630]: volumio-time-update-util: Retrying in 5 seconds... Feb 21 08:33:17 volumio-pc dhcpcd[759]: eth0: offered 192.168.18.49 from 192.168.18.1 Feb 21 08:33:17 volumio-pc volumio[940]: info: Discovery: this is already registered, 0f9d2d3f-ac35-44a6-a4f8-08ee2d3b1cc3 Feb 21 08:33:17 volumio-pc volumio[940]: info: Discovery: Found device PI SCREEN Feb 21 08:33:17 volumio-pc dhcpcd[759]: eth0: probing address 192.168.18.49/24 Feb 21 08:33:18 volumio-pc ntpd[897]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 21 08:33:20 volumio-pc volumio-time-update[630]: volumio-time-update-util: Fetching time from Volumio... Feb 21 08:33:22 volumio-pc dhcpcd[759]: eth0: leased 192.168.18.49 for 604800 seconds Feb 21 08:33:22 volumio-pc dhcpcd[759]: eth0: adding route to 192.168.18.0/24 Feb 21 08:33:22 volumio-pc dhcpcd[759]: eth0: changing default route via 192.168.18.1 Feb 21 08:33:22 volumio-pc avahi-daemon[645]: Registering new address record for 192.168.18.49 on eth0.IPv4. Feb 21 08:33:22 volumio-pc volumio[940]: verbose: New Socket.io Connection to 192.168.18.49:3000 from 192.168.18.74 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 21 08:33:22 volumio-pc volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 21 08:33:22 volumio-pc volumio[940]: info: CoreCommandRouter::volumioGetState Feb 21 08:33:22 volumio-pc volumio[940]: info: CorePlayQueue::getTrack 0 Feb 21 08:33:22 volumio-pc ntpd[897]: ntpd exiting on signal 15 (Terminated) Feb 21 08:33:22 volumio-pc systemd[1]: Stopping Network Time Service... Feb 21 08:33:22 volumio-pc systemd[1]: ntp.service: Succeeded. Feb 21 08:33:22 volumio-pc systemd[1]: Stopped Network Time Service. Feb 21 08:33:22 volumio-pc systemd[1]: Starting Network Time Service... Feb 21 08:33:22 volumio-pc ntpd[2318]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 21 08:33:22 volumio-pc ntpd[2318]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 21 08:33:22 volumio-pc systemd[1]: Started Network Time Service. Feb 21 08:33:22 volumio-pc ntpd[2325]: proto: precision = 0.063 usec (-24) Feb 21 08:33:22 volumio-pc ntpd[2325]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 21 08:33:22 volumio-pc ntpd[2325]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 21 08:33:22 volumio-pc ntpd[2325]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 787 days ago Feb 21 08:33:22 volumio-pc ntpd[2325]: Listen and drop on 0 v6wildcard [::]:123 Feb 21 08:33:22 volumio-pc ntpd[2325]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 21 08:33:22 volumio-pc ntpd[2325]: Listen normally on 2 lo 127.0.0.1:123 Feb 21 08:33:22 volumio-pc ntpd[2325]: Listen normally on 3 eth0 169.254.5.61:123 Feb 21 08:33:22 volumio-pc ntpd[2325]: Listen normally on 4 eth0 192.168.18.49:123 Feb 21 08:33:22 volumio-pc ntpd[2325]: Listening on routing socket on fd #21 for interface updates Feb 21 08:33:22 volumio-pc ntpd[2325]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 21 08:33:22 volumio-pc ntpd[2325]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 21 08:33:22 volumio-pc dhcpcd[759]: eth0: deleting route to 169.254.0.0/16 Feb 21 08:33:22 volumio-pc avahi-daemon[645]: Withdrawing address record for 169.254.5.61 on eth0. Feb 21 08:33:22 volumio-pc avahi-daemon[645]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.5.61. Feb 21 08:33:22 volumio-pc avahi-daemon[645]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.18.49. Feb 21 08:33:23 volumio-pc volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 21 08:33:23 volumio-pc go-librespot[2254]: time="2025-02-21T08:33:23+01:00" level=debug msg="new websocket client" Feb 21 08:33:23 volumio-pc volumio[940]: info: Connection to go-librespot Websocket established Feb 21 08:33:23 volumio-pc volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 21 08:33:23 volumio-pc volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 21 08:33:23 volumio-pc volumio[940]: info: CoreCommandRouter::volumioGetState Feb 21 08:33:23 volumio-pc volumio[940]: info: CorePlayQueue::getTrack 0 Feb 21 08:33:23 volumio-pc volumio[940]: info: Discovery: this is already registered, cefc5e59-57b6-49fa-9acb-52f42f7fd6ac Feb 21 08:33:23 volumio-pc volumio[940]: info: Discovery: Found device Volumio PC Feb 21 08:33:23 volumio-pc volumio[940]: info: CoreCommandRouter::volumioGetState Feb 21 08:33:23 volumio-pc volumio[940]: info: CorePlayQueue::getTrack 0 Feb 21 08:33:23 volumio-pc ntpd[2325]: Soliciting pool server 213.222.217.11 Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 21 08:33:23 volumio-pc volumio[940]: info: Adding plugin bluetooth to MyMusic Plugins Feb 21 08:33:23 volumio-pc volumio[940]: info: Adding plugin multiroom to MyMusic Plugins Feb 21 08:33:23 volumio-pc volumio[940]: info: Adding plugin metavolumio to MyMusic Plugins Feb 21 08:33:23 volumio-pc volumio[940]: info: Adding plugin cd_controller to MyMusic Plugins Feb 21 08:33:23 volumio-pc volumio[940]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 21 08:33:23 volumio-pc volumio[940]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 21 08:33:23 volumio-pc volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 08:33:23 volumio-pc volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 08:33:23 volumio-pc volumio[940]: info: Starting MyVolumio Remote Streaming Endpoints Feb 21 08:33:23 volumio-pc volumio[940]: info: MyVolumio login type: Token Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 21 08:33:23 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 21 08:33:24 volumio-pc volumio[940]: info: Starting Streaming Service Transparent Proxy Feb 21 08:33:24 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 21 08:33:24 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 21 08:33:24 volumio-pc volumio[940]: info: Streaming services startup Feb 21 08:33:24 volumio-pc volumio[940]: info: Starting Streaming Daemon Feb 21 08:33:24 volumio-pc sudo[2353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 21 08:33:24 volumio-pc sudo[2353]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 08:33:24 volumio-pc sudo[2353]: pam_unix(sudo:session): session closed for user root Feb 21 08:33:24 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 21 08:33:24 volumio-pc volumio[940]: error: Cannot start Volumio Streaming Daemon Feb 21 08:33:24 volumio-pc volumio[940]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 21 08:33:24 volumio-pc volumio[940]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 21 08:33:24 volumio-pc ntpd[2325]: Soliciting pool server 89.161.47.131 Feb 21 08:33:24 volumio-pc ntpd[2325]: Deleting interface #3 eth0, 169.254.5.61#123, interface stats: received=0, sent=0, dropped=0, active_time=2 secs Feb 21 08:33:24 volumio-pc ntpd[2325]: Soliciting pool server 193.70.94.182 Feb 21 08:33:24 volumio-pc volumio[940]: STREAMING PROXY: Starting server on port 3245 Feb 21 08:33:24 volumio-pc volumio[940]: Node JS runtime: 14 Feb 21 08:33:24 volumio-pc volumio[940]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 21 08:33:25 volumio-pc ntpd[2325]: Soliciting pool server 85.115.212.250 Feb 21 08:33:25 volumio-pc ntpd[2325]: Soliciting pool server 51.68.141.5 Feb 21 08:33:25 volumio-pc ntpd[2325]: Soliciting pool server 193.59.134.156 Feb 21 08:33:25 volumio-pc volumio[940]: info: MyVolumio token set successfully Feb 21 08:33:25 volumio-pc volumio[940]: info: MYVOLUMIO: Adding device Feb 21 08:33:25 volumio-pc volumio[940]: info: MYVOLUMIO: Evaluating Server Feb 21 08:33:26 volumio-pc volumio[940]: info: MyVolumio Plan changed: premium Feb 21 08:33:26 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 21 08:33:26 volumio-pc volumio[940]: info: Removing browser output: myVolumio user plan is not superstar Feb 21 08:33:26 volumio-pc volumio[940]: info: Removing audio output: Feb 21 08:33:26 volumio-pc volumio[940]: info: MYVOLUMIO: Adding device Feb 21 08:33:26 volumio-pc volumio[940]: info: MYVOLUMIO: Evaluating Server Feb 21 08:33:26 volumio-pc volumio-time-update[630]: volumio-time-update-util: Setting system time to: 2025-02-21 08:33:26 Feb 21 08:33:26 volumio-pc volumio[940]: info: Remote config written successfully Feb 21 08:33:26 volumio-pc volumio[940]: info: Starting Tunnel 1 Feb 21 08:33:26 volumio-pc volumio[940]: info: Starting Tunnel Connection Checker Feb 21 08:33:26 volumio-pc sudo[2397]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-02-21 08:33:26 Feb 21 08:33:26 volumio-pc sudo[2397]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 08:33:26 volumio-pc volumio[940]: info: Getting Spotify volume Feb 21 08:33:26 volumio-pc volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 21 08:33:26 volumio-pc dbus-daemon[643]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.24' (uid=0 pid=2398 comm="timedatectl set-time 2025-02-21 08:33:26 ") Feb 21 08:33:26 volumio-pc volumio[940]: info: CoreCommandRouter::volumioGetState Feb 21 08:33:26 volumio-pc volumio[940]: info: CorePlayQueue::getTrack 0 Feb 21 08:33:26 volumio-pc systemd[1]: Starting Time & Date Service... Feb 21 08:33:26 volumio-pc dbus-daemon[643]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 21 08:33:26 volumio-pc systemd[1]: Started Time & Date Service. Feb 21 08:33:26 volumio-pc systemd-timedated[2399]: Changed local time to Fri Feb 21 08:33:26 2025 Feb 21 08:33:26 volumio-pc sudo[2397]: pam_unix(sudo:session): session closed for user root Feb 21 08:33:26 volumio-pc volumio-time-update[630]: volumio-time-update-util: System time updated successfully. Feb 21 08:33:26 volumio-pc systemd[1]: Started Volumio Time Update Utility. Feb 21 08:33:26 volumio-pc systemd[1]: Reached target Multi-User System. Feb 21 08:33:26 volumio-pc systemd[1]: Reached target Graphical Interface. Feb 21 08:33:26 volumio-pc systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 21 08:33:26 volumio-pc systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 21 08:33:26 volumio-pc systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 21 08:33:26 volumio-pc systemd[1]: Startup finished in 7.175s (kernel) + 2min 17.008s (userspace) = 2min 24.184s. Feb 21 08:33:26 volumio-pc volumio[940]: info: MYVolumio Device enabled Feb 21 08:33:26 volumio-pc volumio[940]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 21 08:33:26 volumio-pc volumio[940]: info: MyVolumio status changed Feb 21 08:33:26 volumio-pc volumio[940]: info: Streaming services startup Feb 21 08:33:26 volumio-pc volumio[940]: info: Starting Streaming Daemon Feb 21 08:33:26 volumio-pc volumio[940]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Feb 21 08:33:26 volumio-pc volumio[940]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 08:33:26 volumio-pc sudo[2418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 21 08:33:26 volumio-pc sudo[2418]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 21 08:33:26 volumio-pc volumio[940]: Error: Unable to resolve or reject the same promise twice Feb 21 08:33:26 volumio-pc volumio[940]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 21 08:33:26 volumio-pc volumio[940]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Feb 21 08:33:26 volumio-pc volumio[940]: at Socket.emit (events.js:412:35) Feb 21 08:33:26 volumio-pc volumio[940]: at endReadableNT (internal/streams/readable.js:1333:12) Feb 21 08:33:26 volumio-pc volumio[940]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Feb 21 08:33:26 volumio-pc volumio[940]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 08:33:26 volumio-pc sudo[2418]: pam_unix(sudo:session): session closed for user root Feb 21 08:33:26 volumio-pc sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-21 08:32 Feb 21 08:33:26 volumio-pc sudo[2429]: 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"