-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-07-07 18:24:27 CEST. -- Jul 07 18:23:02 garaz-volumio go-librespot[3292]: time="2025-07-07T18:23:02+02: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" Jul 07 18:23:02 garaz-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 07 18:23:02 garaz-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 07 18:23:02 garaz-volumio volumio[2967]: (node:2967) UnhandledPromiseRejectionWarning: Error: socket hang up Jul 07 18:23:02 garaz-volumio volumio[2967]: at connResetException (internal/errors.js:607:14) Jul 07 18:23:02 garaz-volumio volumio[2967]: at Socket.socketOnEnd (_http_client.js:493:23) Jul 07 18:23:02 garaz-volumio volumio[2967]: at Socket.emit (events.js:327:22) Jul 07 18:23:02 garaz-volumio volumio[2967]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 07 18:23:02 garaz-volumio volumio[2967]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 07 18:23:02 garaz-volumio volumio[2967]: (node:2967) 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) Jul 07 18:23:02 garaz-volumio volumio[2967]: info: Connection to go-librespot Websocket closed Jul 07 18:23:04 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to 192.168.100.123:3000 from 192.168.100.121 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 07 18:23:05 garaz-volumio volumio[2967]: info: Initializing connection to go-librespot Websocket Jul 07 18:23:05 garaz-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 07 18:23:05 garaz-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jul 07 18:23:05 garaz-volumio systemd[1]: Stopped go-librespot Daemon. Jul 07 18:23:05 garaz-volumio systemd[1]: Started go-librespot Daemon. Jul 07 18:23:05 garaz-volumio go-librespot[3399]: Librespot-go daemon starting... Jul 07 18:23:05 garaz-volumio go-librespot[3399]: time="2025-07-07T18:23:05+02:00" level=info msg="generated new device id: 3f5a2a6819b331ba28c1c932fcd9d96c96745c65" Jul 07 18:23:05 garaz-volumio go-librespot[3399]: time="2025-07-07T18:23:05+02:00" level=debug msg="stored credentials found for 31ykrfcoejvq3eri6uosxaghgvou" Jul 07 18:23:19 garaz-volumio volumio[2967]: info: Discovery: Disconnected from remote: 192.168.100.120 Jul 07 18:23:22 garaz-volumio volumio[2967]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.819&uuid=70c06b48de3d14bcf18d3b7865feb41e" http://updates.volumio.org/downloader-v1/track-device Jul 07 18:23:22 garaz-volumio volumio[2967]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 07 18:23:22 garaz-volumio volumio[2967]: Dload Upload Total Spent Left Speed Jul 07 18:23:22 garaz-volumio volumio[2967]: [2.2K blob data] Jul 07 18:23:22 garaz-volumio volumio[2967]: retrying in 5 seconds, trial 1 Jul 07 18:23:22 garaz-volumio volumio[2967]: info: Volumio Calling Home Jul 07 18:23:25 garaz-volumio volumio[2967]: info: Discovery: Remote connection error: 192.168.100.120 Jul 07 18:23:25 garaz-volumio volumio[2967]: info: Discovery: Remote connection timeout: 192.168.100.120 Jul 07 18:23:28 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 07 18:23:29 garaz-volumio volumio[2967]: info: Discovery: Connected to remote: 192.168.100.120 Jul 07 18:23:34 garaz-volumio go-librespot[3399]: time="2025-07-07T18:23:34+02: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 35.186.224.24:443: connect: no route to host" Jul 07 18:23:34 garaz-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 07 18:23:35 garaz-volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 07 18:23:38 garaz-volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 07 18:23:38 garaz-volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jul 07 18:23:38 garaz-volumio systemd[1]: Stopped go-librespot Daemon. Jul 07 18:23:38 garaz-volumio systemd[1]: Started go-librespot Daemon. Jul 07 18:23:38 garaz-volumio go-librespot[3461]: Librespot-go daemon starting... Jul 07 18:23:38 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:38+02:00" level=info msg="generated new device id: 8aa043f6af87dc44edff3af9489b1bcba6ec2253" Jul 07 18:23:38 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:38+02:00" level=debug msg="stored credentials found for 31ykrfcoejvq3eri6uosxaghgvou" Jul 07 18:23:41 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to 192.168.100.123:3000 from 192.168.100.121 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 07 18:23:41 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to 192.168.100.123:3000 from 192.168.100.121 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 07 18:23:41 garaz-volumio volumio[2967]: info: Volumio called home Jul 07 18:23:42 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:42+02:00" level=debug msg="new websocket client" Jul 07 18:23:42 garaz-volumio volumio[2967]: info: Connection to go-librespot Websocket established Jul 07 18:23:42 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 07 18:23:42 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 07 18:23:42 garaz-volumio volumio[2967]: info: CoreCommandRouter::volumioGetState Jul 07 18:23:42 garaz-volumio volumio[2967]: info: CorePlayQueue::getTrack 0 Jul 07 18:23:42 garaz-volumio volumio[2967]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Jul 07 18:23:44 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to 192.168.100.123:3000 from 192.168.100.121 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jul 07 18:23:45 garaz-volumio volumio[2967]: info: Getting Spotify volume Jul 07 18:23:45 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jul 07 18:23:45 garaz-volumio volumio[2967]: info: CoreCommandRouter::volumioGetState Jul 07 18:23:45 garaz-volumio volumio[2967]: info: CorePlayQueue::getTrack 0 Jul 07 18:23:45 garaz-volumio volumio[2967]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Jul 07 18:23:49 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 07 18:23:49 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:49+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 07 18:23:49 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:49+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 07 18:23:49 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:49+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 07 18:23:49 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:49+02:00" level=debug msg="zeroconf server listening on port 45617" Jul 07 18:23:49 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 18:23:50 garaz-volumio volumio[2967]: info: CoreCommandRouter::volumioGetState Jul 07 18:23:50 garaz-volumio volumio[2967]: info: CorePlayQueue::getTrack 0 Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 07 18:23:50 garaz-volumio volumio[2967]: info: Adding plugin bluetooth to MyMusic Plugins Jul 07 18:23:50 garaz-volumio volumio[2967]: info: Adding plugin multiroom to MyMusic Plugins Jul 07 18:23:50 garaz-volumio volumio[2967]: info: Adding plugin metavolumio to MyMusic Plugins Jul 07 18:23:50 garaz-volumio volumio[2967]: info: Adding plugin cd_controller to MyMusic Plugins Jul 07 18:23:50 garaz-volumio volumio[2967]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 07 18:23:50 garaz-volumio volumio[2967]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 07 18:23:50 garaz-volumio volumio[2967]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 07 18:23:50 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 07 18:23:52 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:52+02:00" level=debug msg="obtained new client token: AAA7Yjn58XqnY2bc92SU29SanVVTua2wB7cJVvMhTTD6WsDth3s4uDn1ZISlvll6t+px4rFLlfTwJdBFD0JOHJAe6ic4B1QjhVxY8DtRpFwJQLI95t5rz8yfO2z8xoQSzCuQJw4SDDRXSDcAXVt58k3j5OjAJbSJpgRaZDw5MQLhTqzhuU0OsUrYjcbGkXDiBqbzUr8m7Eqzmz6auJ5sENS32r6LdrIWGIN1a2vo3F0fNbmNlN5d+rDU" Jul 07 18:23:52 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 07 18:23:52 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 07 18:23:52 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 18:23:52 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 18:23:52 garaz-volumio volumio[2967]: info: Starting MyVolumio Remote Streaming Endpoints Jul 07 18:23:52 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:52+02:00" level=debug msg="completed keyexchange" Jul 07 18:23:52 garaz-volumio volumio[2967]: info: MyVolumio login type: Token Jul 07 18:23:52 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 07 18:23:52 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 07 18:23:52 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:52+02:00" level=debug msg="completed challenge" Jul 07 18:23:53 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:53+02:00" level=debug msg="authenticated as 31ykrfcoejvq3eri6uosxaghgvou" Jul 07 18:23:54 garaz-volumio volumio[2967]: info: Starting Streaming Service Transparent Proxy Jul 07 18:23:54 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 07 18:23:54 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 07 18:23:54 garaz-volumio volumio[2967]: info: Streaming services startup Jul 07 18:23:54 garaz-volumio go-librespot[3461]: time="2025-07-07T18:23:54+02:00" level=debug msg="authenticated as 31ykrfcoejvq3eri6uosxaghgvou" Jul 07 18:23:54 garaz-volumio volumio[2967]: info: Starting Streaming Daemon Jul 07 18:23:54 garaz-volumio sudo[3549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 07 18:23:54 garaz-volumio sudo[3549]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 18:23:54 garaz-volumio sudo[3549]: pam_unix(sudo:session): session closed for user root Jul 07 18:23:54 garaz-volumio volumio[2967]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 07 18:23:54 garaz-volumio volumio[2967]: error: error Jul 07 18:23:54 garaz-volumio volumio[2967]: error: Cannot start Volumio Streaming Daemon Jul 07 18:23:54 garaz-volumio volumio[2967]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 07 18:23:54 garaz-volumio volumio[2967]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 07 18:23:56 garaz-volumio volumio[2967]: error: MyVolumio Custom Token format not valid, refreshing it Jul 07 18:23:56 garaz-volumio volumio[2967]: STREAMING PROXY: Starting server on port 3245 Jul 07 18:23:56 garaz-volumio volumio[2967]: Node JS runtime: 14 Jul 07 18:23:59 garaz-volumio volumio[2967]: info: Discovery: Disconnected from remote: 192.168.100.120 Jul 07 18:23:59 garaz-volumio volumio[2967]: info: Discovery: Disconnected from remote: 192.168.100.120 Jul 07 18:24:02 garaz-volumio go-librespot[3461]: time="2025-07-07T18:24:02+02:00" level=debug msg="dealer connection opened" Jul 07 18:24:02 garaz-volumio go-librespot[3461]: time="2025-07-07T18:24:02+02:00" level=debug msg="initializing zeroconf session, username: 31ykrfcoejvq3eri6uosxaghgvou" Jul 07 18:24:02 garaz-volumio go-librespot[3461]: time="2025-07-07T18:24:02+02:00" level=debug msg="received connection id: MjgxNmQ1ZDgtZGJhMy00YmY3LWEyYmYtMjY0MTNjNjI2MGVkK2RlYWxlcit0Y3A6Ly8wYWNhNDI0My5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArODM3RjVDNEU5QjI4MUE2QzFDRDk0RTQyNEUyMTczNkQ5OTUzRUYwMTAwRjNENjcxNDVFQTlDOTQxOTI1MzFGMg==" Jul 07 18:24:02 garaz-volumio go-librespot[3461]: time="2025-07-07T18:24:02+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 07 18:24:02 garaz-volumio go-librespot[3461]: time="2025-07-07T18:24:02+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 07 18:24:02 garaz-volumio volumio[2967]: info: Spotify volume: 100 Jul 07 18:24:04 garaz-volumio volumio[2967]: info: CoreCommandRouter::volumioGetState Jul 07 18:24:04 garaz-volumio volumio[2967]: info: CorePlayQueue::getTrack 0 Jul 07 18:24:05 garaz-volumio volumio[2967]: info: Discovery: Remote connection error: 192.168.100.120 Jul 07 18:24:05 garaz-volumio volumio[2967]: info: Discovery: Remote connection error: 192.168.100.120 Jul 07 18:24:05 garaz-volumio volumio[2967]: info: Discovery: Remote connection timeout: 192.168.100.120 Jul 07 18:24:05 garaz-volumio volumio[2967]: info: Discovery: Remote connection timeout: 192.168.100.120 Jul 07 18:24:05 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to 192.168.100.123:3000 from 192.168.100.121 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 07 18:24:05 garaz-volumio volumio[2967]: info: Listing playlists Jul 07 18:24:05 garaz-volumio volumio[2967]: info: Listing playlists Jul 07 18:24:05 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to 192.168.100.123:3000 from 192.168.100.120 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jul 07 18:24:07 garaz-volumio go-librespot[3461]: time="2025-07-07T18:24:07+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 07 18:24:07 garaz-volumio go-librespot[3461]: time="2025-07-07T18:24:07+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 07 18:24:07 garaz-volumio go-librespot[3461]: time="2025-07-07T18:24:07+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 07 18:24:07 garaz-volumio go-librespot[3461]: time="2025-07-07T18:24:07+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 07 18:24:07 garaz-volumio go-librespot[3461]: time="2025-07-07T18:24:07+02:00" level=debug msg="autoplay enabled: false" Jul 07 18:24:07 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 07 18:24:07 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jul 07 18:24:07 garaz-volumio volumio[2967]: info: CoreCommandRouter::volumioGetState Jul 07 18:24:07 garaz-volumio volumio[2967]: info: CorePlayQueue::getTrack 0 Jul 07 18:24:07 garaz-volumio volumio[2967]: info: Discovery: Connected to remote: 192.168.100.120 Jul 07 18:24:08 garaz-volumio volumio[2967]: verbose: New Socket.io Connection to 192.168.100.123:3000 from 192.168.100.120 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jul 07 18:24:08 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 07 18:24:08 garaz-volumio volumio[2967]: info: MyVolumio login type: Token Jul 07 18:24:08 garaz-volumio volumio[2967]: info: CoreCommandRouter::volumioGetState Jul 07 18:24:08 garaz-volumio volumio[2967]: info: CorePlayQueue::getTrack 0 Jul 07 18:24:09 garaz-volumio volumio[2967]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 07 18:24:09 garaz-volumio volumio[2967]: info: CoreCommandRouter::volumioGetState Jul 07 18:24:09 garaz-volumio volumio[2967]: info: CorePlayQueue::getTrack 0 Jul 07 18:24:10 garaz-volumio volumio[2967]: info: MyVolumio token set successfully Jul 07 18:24:10 garaz-volumio volumio[2967]: info: MYVOLUMIO: Adding device Jul 07 18:24:10 garaz-volumio volumio[2967]: info: MYVOLUMIO: Evaluating Server Jul 07 18:24:11 garaz-volumio volumio[2967]: info: MyVolumio status changed Jul 07 18:24:11 garaz-volumio volumio[2967]: info: Streaming services startup Jul 07 18:24:11 garaz-volumio volumio[2967]: info: Starting Streaming Daemon Jul 07 18:24:11 garaz-volumio volumio[2967]: info: Removing browser output: myVolumio user plan is not superstar Jul 07 18:24:11 garaz-volumio volumio[2967]: info: Removing audio output: Jul 07 18:24:11 garaz-volumio volumio[2967]: info: Stoppping Tunnel 1 Jul 07 18:24:11 garaz-volumio sudo[3606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 07 18:24:11 garaz-volumio sudo[3606]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 18:24:11 garaz-volumio sudo[3609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 07 18:24:11 garaz-volumio sudo[3609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 18:24:11 garaz-volumio sudo[3606]: pam_unix(sudo:session): session closed for user root Jul 07 18:24:11 garaz-volumio volumio[2967]: error: Cannot start Volumio Streaming Daemon Jul 07 18:24:11 garaz-volumio volumio[2967]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 07 18:24:11 garaz-volumio volumio[2967]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 07 18:24:11 garaz-volumio sudo[3609]: pam_unix(sudo:session): session closed for user root Jul 07 18:24:11 garaz-volumio volumio[2967]: info: Remote SSH Stopped Jul 07 18:24:12 garaz-volumio volumio[2967]: info: Setting Geolocation for MyVolumio to eu7 Jul 07 18:24:12 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 18:24:12 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 18:24:12 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 18:24:12 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 07 18:24:12 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 07 18:24:12 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 07 18:24:12 garaz-volumio volumio[2967]: info: CoreCommandRouter::volumioGetBrowseSources Jul 07 18:24:12 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 18:24:13 garaz-volumio volumio[2967]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 07 18:24:13 garaz-volumio volumio[2967]: info: Updating MyVolumio device info Jul 07 18:24:13 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 18:24:14 garaz-volumio volumio[2967]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 07 18:24:17 garaz-volumio volumio[2967]: info: MYVOLUMIO: Adding device Jul 07 18:24:17 garaz-volumio volumio[2967]: info: MYVOLUMIO: Evaluating Server Jul 07 18:24:18 garaz-volumio volumio[2967]: info: Setting Geolocation for MyVolumio to eu5 Jul 07 18:24:18 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 18:24:19 garaz-volumio volumio[2967]: info: Updating MyVolumio device info Jul 07 18:24:19 garaz-volumio volumio[2967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 18:24:20 garaz-volumio volumio[2967]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 07 18:24:20 garaz-volumio volumio[2967]: info: CoreCommandRouter::volumioGetState Jul 07 18:24:20 garaz-volumio volumio[2967]: info: CorePlayQueue::getTrack 0 Jul 07 18:24:20 garaz-volumio volumio[2967]: info: Listing playlists Jul 07 18:24:20 garaz-volumio volumio[2967]: info: Listing playlists Jul 07 18:24:20 garaz-volumio volumio[2967]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 07 18:24:25 garaz-volumio volumio[2967]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 18:24:25 garaz-volumio volumio[2967]: Error: read ECONNRESET Jul 07 18:24:25 garaz-volumio volumio[2967]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Jul 07 18:24:25 garaz-volumio volumio[2967]: errno: -104, Jul 07 18:24:25 garaz-volumio volumio[2967]: code: 'ECONNRESET', Jul 07 18:24:25 garaz-volumio volumio[2967]: syscall: 'read' Jul 07 18:24:25 garaz-volumio volumio[2967]: } Jul 07 18:24:25 garaz-volumio volumio[2967]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 18:24:27 garaz-volumio sudo[3654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-07 18:23 Jul 07 18:24:27 garaz-volumio sudo[3654]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"