-- Logs begin at Thu 2025-10-16 19:01:46 -03, end at Thu 2025-10-16 19:16:14 -03. -- Oct 16 19:15:01 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:01-03:00" level=debug msg="fetched chunk 8/10, size: 524288" uri="spotify:track:27pXtQDNovp9NddzWScre1" Oct 16 19:15:02 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:02-03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX0SZWVrotKn1" Oct 16 19:15:02 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:02-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Oct 16 19:15:02 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:02-03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX4Y4RhrZqHhr" Oct 16 19:15:02 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:02-03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX9Dh2wgiAwVX" Oct 16 19:15:02 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:02-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Oct 16 19:15:02 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:02-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Oct 16 19:15:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:15:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:15:11 hi-res-audio volumio[1067]: info: Discovery: Getting this device information Oct 16 19:15:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:15:11 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 2 Oct 16 19:15:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:15:14 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:14-03:00" level=debug msg="fetched chunk 9/10, size: 524288" uri="spotify:track:27pXtQDNovp9NddzWScre1" Oct 16 19:15:17 hi-res-audio volumio[1067]: info: Tunnel connection is inactive, restarting it Oct 16 19:15:17 hi-res-audio volumio[1067]: info: Starting Tunnel 1 Oct 16 19:15:17 hi-res-audio volumio[1067]: info: Starting Tunnel Connection Checker Oct 16 19:15:17 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:17-03:00" level=trace msg="sent dealer ping" Oct 16 19:15:18 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:18-03:00" level=trace msg="received dealer pong" Oct 16 19:15:18 hi-res-audio sudo[3622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 16 19:15:18 hi-res-audio sudo[3622]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 19:15:18 hi-res-audio autossh[3132]: received signal to exit (15) Oct 16 19:15:18 hi-res-audio systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 16 19:15:18 hi-res-audio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 16 19:15:18 hi-res-audio systemd[1]: sshtunnel.service: Succeeded. Oct 16 19:15:18 hi-res-audio systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 16 19:15:18 hi-res-audio volumio[1067]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 19:15:18 hi-res-audio volumio[1067]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 19:15:18 hi-res-audio systemd[1]: Started MyVolumio SSH Tunnel. Oct 16 19:15:18 hi-res-audio sudo[3622]: pam_unix(sudo:session): session closed for user root Oct 16 19:15:18 hi-res-audio volumio[1067]: info: Remote SSH Started Oct 16 19:15:18 hi-res-audio autossh[3625]: port set to 0, monitoring disabled Oct 16 19:15:18 hi-res-audio autossh[3625]: starting ssh (count 1) Oct 16 19:15:18 hi-res-audio autossh[3625]: ssh child pid is 3628 Oct 16 19:15:18 hi-res-audio volumio[1067]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Oct 16 19:15:18 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:15:18 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 2 Oct 16 19:15:21 hi-res-audio volumiossh-tunnel[3624]: ssh: connect to host us1.myvolumio.org port 2222: No route to host Oct 16 19:15:21 hi-res-audio autossh[3625]: ssh exited prematurely with status 255; autossh exiting Oct 16 19:15:21 hi-res-audio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Oct 16 19:15:21 hi-res-audio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Oct 16 19:15:21 hi-res-audio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Oct 16 19:15:21 hi-res-audio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Oct 16 19:15:21 hi-res-audio systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 16 19:15:21 hi-res-audio systemd[1]: Started MyVolumio SSH Tunnel. Oct 16 19:15:21 hi-res-audio autossh[3644]: port set to 0, monitoring disabled Oct 16 19:15:21 hi-res-audio autossh[3644]: starting ssh (count 1) Oct 16 19:15:21 hi-res-audio autossh[3644]: ssh child pid is 3647 Oct 16 19:15:22 hi-res-audio volumiossh-tunnel[3642]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Oct 16 19:15:23 hi-res-audio volumiossh-tunnel[3642]: Warning: remote port forwarding failed for listen port 8091 Oct 16 19:15:23 hi-res-audio volumio[1067]: error: An error occurred while listing Spotify album tracks Error: getaddrinfo ENOTFOUND api.spotify.com Oct 16 19:15:27 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:15:27 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:15:27 hi-res-audio volumio[1067]: info: Discovery: Getting this device information Oct 16 19:15:27 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:15:27 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 2 Oct 16 19:15:27 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:15:27 hi-res-audio volumio[1067]: verbose: New Socket.io Connection to 10.10.10.240:3000 from 10.10.10.232 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Oct 16 19:15:27 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:27-03:00" level=debug msg="fetched chunk 10/10, size: 145860" uri="spotify:track:27pXtQDNovp9NddzWScre1" Oct 16 19:15:28 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:15:28 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:15:28 hi-res-audio volumio[1067]: info: Discovery: Getting this device information Oct 16 19:15:28 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:15:28 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 2 Oct 16 19:15:28 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:15:29 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 16 19:15:29 hi-res-audio volumio[1067]: info: In handleBrowseUri, curUri=spotify:album:0KEmmW5iXwzpaSlaamlizL Oct 16 19:15:29 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 16 19:15:29 hi-res-audio volumio[1067]: info: In handleBrowseUri, curUri=spotify:album:0KEmmW5iXwzpaSlaamlizL Oct 16 19:15:31 hi-res-audio volumio[1067]: verbose: New Socket.io Connection to 10.10.10.240:3000 from 10.10.10.232 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Preload queue cleared Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:7E9FZRw2GMdKaW4CFp85Vx Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:6CPTL6sgyCnoPwxllkvRZf Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:5ruowFhfS9jDoGyX68BxRw Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:7wvY7tcgxIZvexzD964KCK Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:3J49YFtSjLMiuyDi6xNHc1 Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:66HAMa4TPeunLEQBpPu0e8 Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:4mMGcUQcKd6xkCTtvsIe6l Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:7oTfgauIhz9Zg5TJpywzjr Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:7E9FZRw2GMdKaW4CFp85Vx in service spop Oct 16 19:15:35 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:7E9FZRw2GMdKaW4CFp85Vx Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Executing endpoint getSimilarAlbums Oct 16 19:15:35 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:6CPTL6sgyCnoPwxllkvRZf in service spop Oct 16 19:15:35 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:6CPTL6sgyCnoPwxllkvRZf Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:5ruowFhfS9jDoGyX68BxRw in service spop Oct 16 19:15:35 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:5ruowFhfS9jDoGyX68BxRw Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Executing endpoint metavolumio Oct 16 19:15:35 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Executing endpoint metavolumio Oct 16 19:15:35 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:7wvY7tcgxIZvexzD964KCK in service spop Oct 16 19:15:35 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:7wvY7tcgxIZvexzD964KCK Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:3J49YFtSjLMiuyDi6xNHc1 in service spop Oct 16 19:15:35 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:3J49YFtSjLMiuyDi6xNHc1 Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:66HAMa4TPeunLEQBpPu0e8 in service spop Oct 16 19:15:35 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:66HAMa4TPeunLEQBpPu0e8 Oct 16 19:15:35 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7E9FZRw2GMdKaW4CFp85Vx","service":"spop","name":"Break My Stride","artist":"Bossa Nova Covers","album":"Bossa Nova Covers","type":"song","duration":248,"albumart":"https://i.scdn.co/image/ab67616d0000b2730986e386b36455aba2d8bd60","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:4mMGcUQcKd6xkCTtvsIe6l in service spop Oct 16 19:15:35 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:4mMGcUQcKd6xkCTtvsIe6l Oct 16 19:15:35 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:7oTfgauIhz9Zg5TJpywzjr in service spop Oct 16 19:15:35 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:7oTfgauIhz9Zg5TJpywzjr Oct 16 19:15:35 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ruowFhfS9jDoGyX68BxRw","service":"spop","name":"Moves Like Jagger","artist":"Bossa Nova Covers","album":"Bossa Nova Covers","type":"song","duration":223,"albumart":"https://i.scdn.co/image/ab67616d0000b2730986e386b36455aba2d8bd60","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:15:37 hi-res-audio volumio[1067]: (node:1067) UnhandledPromiseRejectionWarning: Error: connect EHOSTUNREACH 35.186.224.24:443 Oct 16 19:15:37 hi-res-audio volumio[1067]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 16 19:15:37 hi-res-audio volumio[1067]: (node:1067) 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: 12) Oct 16 19:15:41 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:15:41 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:15:41 hi-res-audio volumio[1067]: info: Discovery: Getting this device information Oct 16 19:15:41 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:15:41 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 2 Oct 16 19:15:41 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:15:41 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7wvY7tcgxIZvexzD964KCK","service":"spop","name":"The Riddle","artist":"Bossa Nova Covers","album":"Bossa Nova Covers","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b2730986e386b36455aba2d8bd60","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:15:41 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:66HAMa4TPeunLEQBpPu0e8","service":"spop","name":"Waiting for Love","artist":"Bossa Nova Covers","album":"Bossa Nova Covers","type":"song","duration":159,"albumart":"https://i.scdn.co/image/ab67616d0000b2730986e386b36455aba2d8bd60","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:15:41 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4mMGcUQcKd6xkCTtvsIe6l","service":"spop","name":"Hooked On a Feeling","artist":"Bossa Nova Covers","album":"Bossa Nova Covers","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b2730986e386b36455aba2d8bd60","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:15:41 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3J49YFtSjLMiuyDi6xNHc1","service":"spop","name":"Lovefool","artist":"Bossa Nova Covers","album":"Bossa Nova Covers","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b2730986e386b36455aba2d8bd60","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:15:42 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 16 19:15:42 hi-res-audio volumio[1067]: info: In handleBrowseUri, curUri=spotify:album:3i1c4TS3vifZBHzCVF18i2 Oct 16 19:15:42 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 16 19:15:42 hi-res-audio volumio[1067]: info: In handleBrowseUri, curUri=spotify:album:3i1c4TS3vifZBHzCVF18i2 Oct 16 19:15:43 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6CPTL6sgyCnoPwxllkvRZf","service":"spop","name":"That's What I Like","artist":"Bossa Nova Covers","album":"Bossa Nova Covers","type":"song","duration":201,"albumart":"https://i.scdn.co/image/ab67616d0000b2730986e386b36455aba2d8bd60","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:15:47 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:47-03:00" level=trace msg="sent dealer ping" Oct 16 19:15:48 hi-res-audio go-librespot[1752]: time="2025-10-16T19:15:48-03:00" level=trace msg="received dealer pong" Oct 16 19:15:49 hi-res-audio volumio[1067]: error: Commandrouter: Cannot explode uri spotify:track:7oTfgauIhz9Zg5TJpywzjr from service spop: Error: Spotify API method getTrack failed: Error: connect EHOSTUNREACH 35.186.224.24:443 Oct 16 19:15:49 hi-res-audio volumio[1067]: (node:1067) UnhandledPromiseRejectionWarning: Error: connect EHOSTUNREACH 35.186.224.24:443 Oct 16 19:15:49 hi-res-audio volumio[1067]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 16 19:15:49 hi-res-audio volumio[1067]: (node:1067) 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: 13) Oct 16 19:15:49 hi-res-audio volumio[1067]: (node:1067) UnhandledPromiseRejectionWarning: Error: connect EHOSTUNREACH 35.186.224.24:443 Oct 16 19:15:49 hi-res-audio volumio[1067]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 16 19:15:49 hi-res-audio volumio[1067]: (node:1067) 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: 14) Oct 16 19:15:56 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 16 19:15:56 hi-res-audio volumio[1067]: info: In handleBrowseUri, curUri=spotify:album:2ZseDlkhp1CYh1bZQnubow Oct 16 19:15:56 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 16 19:15:56 hi-res-audio volumio[1067]: info: In handleBrowseUri, curUri=spotify:album:2ZseDlkhp1CYh1bZQnubow Oct 16 19:16:00 hi-res-audio ntpd[792]: Soliciting pool server 2001:1328:54::34 Oct 16 19:16:01 hi-res-audio volumio[1067]: verbose: New Socket.io Connection to 10.10.10.240:3000 from 10.10.10.232 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Oct 16 19:16:01 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:16:01 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:16:01 hi-res-audio volumio[1067]: info: Discovery: Getting this device information Oct 16 19:16:01 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:16:01 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 2 Oct 16 19:16:01 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preload queue cleared Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:1lzUIyI4UqUVzKXsRtBcbU Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:0j0fypZtR9WqBehhyRjDiu Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:1j7rPaL7NuFgMYB0BFT8ej Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:0ar8BJkBnTlWWHcDsKQreD Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:3soyaJP7jQ6FejBSmTv8tg Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:0mcqmtwFM1nxvSlLmZtp0M Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:2dH24t34XWKN6lPbq33pRY Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:3329G7dGEVmYSp34WN2KMe Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:0EsZbb4YSOBhlQVEsoErgl Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:78igxfQCKcsgQ1yeV0Vmpz Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:0u24tQC5ExX9EOJRQdqb9B Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:0LwTFtz29Ue0sGufGX6RDT Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:5TKtT4PingIaRt1KyV9cGX Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:2C26c1IgQmYj1rN0HEUlH3 Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:1XGrhi7VFMkDDp4B25SIH1 Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:68EWcOifp6DFFYVTer13cH Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:2YhK5X9SKQPLDKzU8qLOv5 Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:7tWZlWVwepWKyrsSSL21fz Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:5AFYa5bOwxFZUCzOnCnqjE Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:2hlN5jxIb81ALMohuE5tva Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:7CEL9W6rLfVql3Hvb7s9c4 Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:6dimWVW3rAV5Rmm3sMT73c Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:4IpWiF48f5OcHMhLE6cd2J Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:2oRy6hcs9wQzQtYWDK5HzT Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:1iCYLU5z6ubDwdvUeOV0Ql Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:1o3YWQ5hW5Law3nQPHjAel Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:6IgrEshjRz3vY22PsOcGAc Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:1RzW7vsPQ2dD8aN162BUQ2 Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:16pADzCmdGNsRovl4s1xHZ Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:0YWQTRdLVSLrLI33Bx1Jsh Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:3YNnHPAmDPEgn8EvdxAoc8 Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:2wZ3VHqsBjnHUzVYhhgMS2 Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:48AlXS0xDuwbu9IespKMFq Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:2jRl01h8kkqdISJeitqmBE Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:5fHMUSlXwiBxo4NFs04lcq Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:1vlTxJWCcwivk2qVRY1fyg Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:5tHduxbYCumITM2FkP88HE Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:4JF4zXVymlEv62uBldpm6w Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:1L5Z46VjM15XWr6pjXKeu1 Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Preloading song: spotify:track:2YeJSReOgLqnx0tex1RhEw Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1lzUIyI4UqUVzKXsRtBcbU in service spop Oct 16 19:16:05 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1lzUIyI4UqUVzKXsRtBcbU Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Executing endpoint getSimilarAlbums Oct 16 19:16:05 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0j0fypZtR9WqBehhyRjDiu in service spop Oct 16 19:16:05 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0j0fypZtR9WqBehhyRjDiu Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1j7rPaL7NuFgMYB0BFT8ej in service spop Oct 16 19:16:05 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1j7rPaL7NuFgMYB0BFT8ej Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Executing endpoint metavolumio Oct 16 19:16:05 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 19:16:05 hi-res-audio volumio[1067]: info: Executing endpoint metavolumio Oct 16 19:16:05 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0ar8BJkBnTlWWHcDsKQreD in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0ar8BJkBnTlWWHcDsKQreD Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:3soyaJP7jQ6FejBSmTv8tg in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:3soyaJP7jQ6FejBSmTv8tg Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0mcqmtwFM1nxvSlLmZtp0M in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0mcqmtwFM1nxvSlLmZtp0M Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1lzUIyI4UqUVzKXsRtBcbU","service":"spop","name":"Holding Back the Years","artist":"Bossanatics","album":"Best of Bossa Lounge (International Version)","type":"song","duration":259,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc9b95064dbb61964c2de1d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2dH24t34XWKN6lPbq33pRY in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2dH24t34XWKN6lPbq33pRY Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0j0fypZtR9WqBehhyRjDiu","service":"spop","name":"Let's Stay Together (feat. Daniela Maia)","artist":"Nova Jazzers","album":"Best of Bossa Lounge (International Version)","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc9b95064dbb61964c2de1d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:3329G7dGEVmYSp34WN2KMe in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:3329G7dGEVmYSp34WN2KMe Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0EsZbb4YSOBhlQVEsoErgl in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0EsZbb4YSOBhlQVEsoErgl Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:78igxfQCKcsgQ1yeV0Vmpz in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:78igxfQCKcsgQ1yeV0Vmpz Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0u24tQC5ExX9EOJRQdqb9B in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0u24tQC5ExX9EOJRQdqb9B Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0LwTFtz29Ue0sGufGX6RDT in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0LwTFtz29Ue0sGufGX6RDT Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:5TKtT4PingIaRt1KyV9cGX in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:5TKtT4PingIaRt1KyV9cGX Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2C26c1IgQmYj1rN0HEUlH3 in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2C26c1IgQmYj1rN0HEUlH3 Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1XGrhi7VFMkDDp4B25SIH1 in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1XGrhi7VFMkDDp4B25SIH1 Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:68EWcOifp6DFFYVTer13cH in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:68EWcOifp6DFFYVTer13cH Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2YhK5X9SKQPLDKzU8qLOv5 in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2YhK5X9SKQPLDKzU8qLOv5 Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:7tWZlWVwepWKyrsSSL21fz in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:7tWZlWVwepWKyrsSSL21fz Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:5AFYa5bOwxFZUCzOnCnqjE in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:5AFYa5bOwxFZUCzOnCnqjE Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2hlN5jxIb81ALMohuE5tva in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2hlN5jxIb81ALMohuE5tva Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:7CEL9W6rLfVql3Hvb7s9c4 in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:7CEL9W6rLfVql3Hvb7s9c4 Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:6dimWVW3rAV5Rmm3sMT73c in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:6dimWVW3rAV5Rmm3sMT73c Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:4IpWiF48f5OcHMhLE6cd2J in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:4IpWiF48f5OcHMhLE6cd2J Oct 16 19:16:06 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2oRy6hcs9wQzQtYWDK5HzT in service spop Oct 16 19:16:06 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2oRy6hcs9wQzQtYWDK5HzT Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1iCYLU5z6ubDwdvUeOV0Ql in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1iCYLU5z6ubDwdvUeOV0Ql Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1o3YWQ5hW5Law3nQPHjAel in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1o3YWQ5hW5Law3nQPHjAel Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:6IgrEshjRz3vY22PsOcGAc in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:6IgrEshjRz3vY22PsOcGAc Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1RzW7vsPQ2dD8aN162BUQ2 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1RzW7vsPQ2dD8aN162BUQ2 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:16pADzCmdGNsRovl4s1xHZ in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:16pADzCmdGNsRovl4s1xHZ Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0YWQTRdLVSLrLI33Bx1Jsh in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0YWQTRdLVSLrLI33Bx1Jsh Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:3YNnHPAmDPEgn8EvdxAoc8 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:3YNnHPAmDPEgn8EvdxAoc8 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2wZ3VHqsBjnHUzVYhhgMS2 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2wZ3VHqsBjnHUzVYhhgMS2 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:48AlXS0xDuwbu9IespKMFq in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:48AlXS0xDuwbu9IespKMFq Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2jRl01h8kkqdISJeitqmBE in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2jRl01h8kkqdISJeitqmBE Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:5fHMUSlXwiBxo4NFs04lcq in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:5fHMUSlXwiBxo4NFs04lcq Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1vlTxJWCcwivk2qVRY1fyg in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1vlTxJWCcwivk2qVRY1fyg Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Preload queue cleared Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::ClearQueue Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::stop Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::stPlaybackTimer Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::updateTrackBlock Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrackBlock Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::pushState Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 2 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioPushState Oct 16 19:16:07 hi-res-audio volumio[1067]: info: MRS: Pushing multiroomSync output update for this device Oct 16 19:16:07 hi-res-audio volumio[1067]: info: MRS: Pushing multiroomSync output Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 2 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::serviceStop Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 2 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::serviceStop Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Spotify Stop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: SPOTIFY STOP Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: {"status":"play","position":2,"title":"Rhode Island Is Famous for You","artist":"Flor De Lis","album":"Bossanova Cocktail Bar","albumart":"https://i.scdn.co/image/ab67616d0000b273de3b5ede8ac2b4f319379ada","uri":"spotify:track:27pXtQDNovp9NddzWScre1","trackType":"spotify","codec":"ogg","seek":0,"duration":140,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":56,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Sending Spotify command to local API: /player/pause Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::clearPlayQueue Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::saveQueue Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioPushQueue Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::addQueueItems Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::addQueueItems Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Preload queue cleared Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:1lzUIyI4UqUVzKXsRtBcbU Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Using cached record of: spotify:track:1lzUIyI4UqUVzKXsRtBcbU Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioPushQueue Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::saveQueue Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::updateTrackBlock Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrackBlock Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioPlay Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::play index 0 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::addQueueItems Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::addQueueItems Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Preload queue cleared Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:0j0fypZtR9WqBehhyRjDiu Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Using cached record of: spotify:track:0j0fypZtR9WqBehhyRjDiu Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:1j7rPaL7NuFgMYB0BFT8ej Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1j7rPaL7NuFgMYB0BFT8ej in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1j7rPaL7NuFgMYB0BFT8ej Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:0ar8BJkBnTlWWHcDsKQreD Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0ar8BJkBnTlWWHcDsKQreD in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0ar8BJkBnTlWWHcDsKQreD Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:3soyaJP7jQ6FejBSmTv8tg Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:3soyaJP7jQ6FejBSmTv8tg in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:3soyaJP7jQ6FejBSmTv8tg Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:0mcqmtwFM1nxvSlLmZtp0M Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0mcqmtwFM1nxvSlLmZtp0M in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0mcqmtwFM1nxvSlLmZtp0M Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:2dH24t34XWKN6lPbq33pRY Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2dH24t34XWKN6lPbq33pRY in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2dH24t34XWKN6lPbq33pRY Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:3329G7dGEVmYSp34WN2KMe Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:3329G7dGEVmYSp34WN2KMe in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:3329G7dGEVmYSp34WN2KMe Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:0EsZbb4YSOBhlQVEsoErgl Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0EsZbb4YSOBhlQVEsoErgl in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0EsZbb4YSOBhlQVEsoErgl Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:78igxfQCKcsgQ1yeV0Vmpz Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:78igxfQCKcsgQ1yeV0Vmpz in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:78igxfQCKcsgQ1yeV0Vmpz Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:0u24tQC5ExX9EOJRQdqb9B Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0u24tQC5ExX9EOJRQdqb9B in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0u24tQC5ExX9EOJRQdqb9B Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:0LwTFtz29Ue0sGufGX6RDT Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0LwTFtz29Ue0sGufGX6RDT in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0LwTFtz29Ue0sGufGX6RDT Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:5TKtT4PingIaRt1KyV9cGX Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:5TKtT4PingIaRt1KyV9cGX in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:5TKtT4PingIaRt1KyV9cGX Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:2C26c1IgQmYj1rN0HEUlH3 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2C26c1IgQmYj1rN0HEUlH3 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2C26c1IgQmYj1rN0HEUlH3 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:1XGrhi7VFMkDDp4B25SIH1 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1XGrhi7VFMkDDp4B25SIH1 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1XGrhi7VFMkDDp4B25SIH1 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:68EWcOifp6DFFYVTer13cH Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:68EWcOifp6DFFYVTer13cH in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:68EWcOifp6DFFYVTer13cH Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:2YhK5X9SKQPLDKzU8qLOv5 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2YhK5X9SKQPLDKzU8qLOv5 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2YhK5X9SKQPLDKzU8qLOv5 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:7tWZlWVwepWKyrsSSL21fz Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:7tWZlWVwepWKyrsSSL21fz in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:7tWZlWVwepWKyrsSSL21fz Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:5AFYa5bOwxFZUCzOnCnqjE Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:5AFYa5bOwxFZUCzOnCnqjE in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:5AFYa5bOwxFZUCzOnCnqjE Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:2hlN5jxIb81ALMohuE5tva Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2hlN5jxIb81ALMohuE5tva in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2hlN5jxIb81ALMohuE5tva Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:7CEL9W6rLfVql3Hvb7s9c4 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:7CEL9W6rLfVql3Hvb7s9c4 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:7CEL9W6rLfVql3Hvb7s9c4 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:6dimWVW3rAV5Rmm3sMT73c Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:6dimWVW3rAV5Rmm3sMT73c in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:6dimWVW3rAV5Rmm3sMT73c Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:4IpWiF48f5OcHMhLE6cd2J Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:4IpWiF48f5OcHMhLE6cd2J in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:4IpWiF48f5OcHMhLE6cd2J Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:2oRy6hcs9wQzQtYWDK5HzT Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2oRy6hcs9wQzQtYWDK5HzT in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2oRy6hcs9wQzQtYWDK5HzT Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:1iCYLU5z6ubDwdvUeOV0Ql Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1iCYLU5z6ubDwdvUeOV0Ql in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1iCYLU5z6ubDwdvUeOV0Ql Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:1o3YWQ5hW5Law3nQPHjAel Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1o3YWQ5hW5Law3nQPHjAel in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1o3YWQ5hW5Law3nQPHjAel Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:6IgrEshjRz3vY22PsOcGAc Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:6IgrEshjRz3vY22PsOcGAc in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:6IgrEshjRz3vY22PsOcGAc Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:1RzW7vsPQ2dD8aN162BUQ2 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1RzW7vsPQ2dD8aN162BUQ2 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1RzW7vsPQ2dD8aN162BUQ2 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:16pADzCmdGNsRovl4s1xHZ Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:16pADzCmdGNsRovl4s1xHZ in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:16pADzCmdGNsRovl4s1xHZ Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:0YWQTRdLVSLrLI33Bx1Jsh Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:0YWQTRdLVSLrLI33Bx1Jsh in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:0YWQTRdLVSLrLI33Bx1Jsh Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:3YNnHPAmDPEgn8EvdxAoc8 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:3YNnHPAmDPEgn8EvdxAoc8 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:3YNnHPAmDPEgn8EvdxAoc8 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:2wZ3VHqsBjnHUzVYhhgMS2 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2wZ3VHqsBjnHUzVYhhgMS2 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2wZ3VHqsBjnHUzVYhhgMS2 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:48AlXS0xDuwbu9IespKMFq Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:48AlXS0xDuwbu9IespKMFq in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:48AlXS0xDuwbu9IespKMFq Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:2jRl01h8kkqdISJeitqmBE Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2jRl01h8kkqdISJeitqmBE in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2jRl01h8kkqdISJeitqmBE Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:5fHMUSlXwiBxo4NFs04lcq Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:5fHMUSlXwiBxo4NFs04lcq in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:5fHMUSlXwiBxo4NFs04lcq Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:1vlTxJWCcwivk2qVRY1fyg Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1vlTxJWCcwivk2qVRY1fyg in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1vlTxJWCcwivk2qVRY1fyg Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:5tHduxbYCumITM2FkP88HE Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:5tHduxbYCumITM2FkP88HE in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:5tHduxbYCumITM2FkP88HE Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:4JF4zXVymlEv62uBldpm6w Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:4JF4zXVymlEv62uBldpm6w in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:4JF4zXVymlEv62uBldpm6w Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:1L5Z46VjM15XWr6pjXKeu1 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:1L5Z46VjM15XWr6pjXKeu1 in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:1L5Z46VjM15XWr6pjXKeu1 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Adding Item to queue: spotify:track:2YeJSReOgLqnx0tex1RhEw Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Exploding uri spotify:track:2YeJSReOgLqnx0tex1RhEw in service spop Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: EXPLODING URI:spotify:track:2YeJSReOgLqnx0tex1RhEw Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::stop Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::play index undefined Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreStateMachine::startPlaybackTimer Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 19:16:07 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 16 19:16:07 hi-res-audio volumio[1067]: info: [1760652967689] ControllerSpotify::clearAddPlayTrack Oct 16 19:16:07 hi-res-audio volumio[1067]: info: Sending Spotify command with payload to local API: /player/play Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: SPOTIFY VOLUME 55 Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: VOLUMIO VOLUME 56 Oct 16 19:16:07 hi-res-audio volumio[1067]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 16 19:16:07 hi-res-audio volumio[1067]: (node:1067) UnhandledPromiseRejectionWarning: Error: connect EHOSTUNREACH 35.186.224.24:443 Oct 16 19:16:07 hi-res-audio volumio[1067]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Oct 16 19:16:07 hi-res-audio volumio[1067]: (node:1067) 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: 15) Oct 16 19:16:07 hi-res-audio volumio[1067]: error: Commandrouter: Cannot explode uri spotify:track:1j7rPaL7NuFgMYB0BFT8ej from service spop: Error: Spotify API method getTrack failed: Error: connect EHOSTUNREACH 35.186.224.24:443 Oct 16 19:16:07 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:07-03:00" level=debug msg="pause track at 133754ms" Oct 16 19:16:07 hi-res-audio volumio[1067]: verbose: New Socket.io Connection to 10.10.10.240:3000 from 10.10.10.232 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Oct 16 19:16:08 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:08-03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 19:16:08 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:08-03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 19:16:08 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:08-03:00" level=trace msg="emitting websocket event: paused" Oct 16 19:16:08 hi-res-audio volumio[1067]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:27pXtQDNovp9NddzWScre1","uri":"spotify:track:27pXtQDNovp9NddzWScre1","play_origin":"go-librespot"}} Oct 16 19:16:08 hi-res-audio volumio[1067]: SPOTIFY: PUSH STATE SPOTIFY Oct 16 19:16:08 hi-res-audio volumio[1067]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":1000,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 19:16:08 hi-res-audio volumio[1067]: info: CoreCommandRouter::servicePushState Oct 16 19:16:08 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:08 hi-res-audio volumio[1067]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":1000,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 19:16:08 hi-res-audio volumio[1067]: verbose: CURRENT POSITION 0 Oct 16 19:16:08 hi-res-audio volumio[1067]: info: CoreStateMachine::syncState stateService pause Oct 16 19:16:08 hi-res-audio volumio[1067]: info: CoreStateMachine::syncState currentStatus stop Oct 16 19:16:08 hi-res-audio volumio[1067]: info: CoreStateMachine::pushState Oct 16 19:16:08 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:08 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:16:08 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioPushState Oct 16 19:16:08 hi-res-audio volumio[1067]: info: MRS: Pushing multiroomSync output update for this device Oct 16 19:16:08 hi-res-audio volumio[1067]: info: MRS: Pushing multiroomSync output Oct 16 19:16:08 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:16:08 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:08 hi-res-audio volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Oct 16 19:16:08 hi-res-audio volumio[1067]: SPOTIFY: SPOTIFY VOLUME 55 Oct 16 19:16:08 hi-res-audio volumio[1067]: SPOTIFY: VOLUMIO VOLUME 56 Oct 16 19:16:08 hi-res-audio volumio[1067]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 16 19:16:08 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:08-03:00" level=debug msg="resolved context of track" uri="spotify:track:1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:08 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:08-03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:08 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:08-03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:09 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:09-03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 19:16:09 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:09-03:00" level=trace msg="emitting websocket event: will_play" Oct 16 19:16:09 hi-res-audio volumio[1067]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:1lzUIyI4UqUVzKXsRtBcbU","uri":"spotify:track:1lzUIyI4UqUVzKXsRtBcbU","play_origin":"go-librespot"}} Oct 16 19:16:09 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:09-03:00" level=debug msg="selected format OGG_VORBIS_320 (5b3ec0e102c6d6ea695868d82d7d922e2f82c245)" uri="spotify:track:1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:09 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:09-03:00" level=debug msg="requested aes key for file 5b3ec0e102c6d6ea695868d82d7d922e2f82c245, gid: 1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:09 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:09-03:00" level=debug msg="skipping dealer message" uri=playback-settings/content-settings-update Oct 16 19:16:09 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:09-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 159" Oct 16 19:16:09 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:09-03:00" level=trace msg="found 2 cdn urls" uri="spotify:track:1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=debug msg="fetched first chunk of 19, total size is 9543864 bytes" uri="spotify:track:1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=info msg="loaded track \"Holding Back the Years\" (paused: false, position: 0ms, duration: 259737ms, prefetched: false)" uri="spotify:track:1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=trace msg="scheduling prefetch in 229s" Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=trace msg="emitting websocket event: metadata" Oct 16 19:16:10 hi-res-audio volumio[1067]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1lzUIyI4UqUVzKXsRtBcbU","name":"Holding Back the Years","artist_names":["Bossanatics","Ana Costa"],"album_name":"Best of Bossa Lounge (International Version)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02fc9b95064dbb61964c2de1d2","position":0,"duration":259737,"release_date":"year:2014 month:6 day:20","track_number":1,"disc_number":1}} Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:1lzUIyI4UqUVzKXsRtBcbU" Oct 16 19:16:10 hi-res-audio volumio[1067]: info: Executing endpoint metavolumio Oct 16 19:16:10 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 19:16:10 hi-res-audio volumio[1067]: info: Executing endpoint metavolumio Oct 16 19:16:10 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 16 19:16:10 hi-res-audio go-librespot[1752]: time="2025-10-16T19:16:10-03:00" level=trace msg="emitting websocket event: playing" Oct 16 19:16:10 hi-res-audio volumio[1067]: info: Executing endpoint metavolumio Oct 16 19:16:10 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 16 19:16:10 hi-res-audio volumio[1067]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:1lzUIyI4UqUVzKXsRtBcbU","uri":"spotify:track:1lzUIyI4UqUVzKXsRtBcbU","resume":false,"play_origin":"go-librespot"}} Oct 16 19:16:10 hi-res-audio volumio[1067]: SPOTIFY: PUSH STATE SPOTIFY Oct 16 19:16:10 hi-res-audio volumio[1067]: SPOTIFY: {"status":"play","service":"spop","title":"Holding Back the Years","artist":"Bossanatics, Ana Costa","album":"Best of Bossa Lounge (International Version)","albumart":"https://i.scdn.co/image/ab67616d00001e02fc9b95064dbb61964c2de1d2","uri":"spotify:track:1lzUIyI4UqUVzKXsRtBcbU","trackType":"spotify","seek":0,"duration":259,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 19:16:10 hi-res-audio volumio[1067]: info: CoreCommandRouter::servicePushState Oct 16 19:16:10 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:10 hi-res-audio volumio[1067]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Holding Back the Years","artist":"Bossanatics, Ana Costa","album":"Best of Bossa Lounge (International Version)","albumart":"https://i.scdn.co/image/ab67616d00001e02fc9b95064dbb61964c2de1d2","uri":"spotify:track:1lzUIyI4UqUVzKXsRtBcbU","trackType":"spotify","seek":0,"duration":259,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 19:16:10 hi-res-audio volumio[1067]: verbose: CURRENT POSITION 0 Oct 16 19:16:10 hi-res-audio volumio[1067]: info: CoreStateMachine::syncState stateService play Oct 16 19:16:10 hi-res-audio volumio[1067]: info: CoreStateMachine::syncState currentStatus stop Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 19:16:11 hi-res-audio volumio[1067]: info: Discovery: Getting this device information Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: PUSH STATE SPOTIFY Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: {"status":"play","service":"spop","title":"Holding Back the Years","artist":"Bossanatics, Ana Costa","album":"Best of Bossa Lounge (International Version)","albumart":"https://i.scdn.co/image/ab67616d00001e02fc9b95064dbb61964c2de1d2","uri":"spotify:track:1lzUIyI4UqUVzKXsRtBcbU","trackType":"spotify","seek":0,"duration":259,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::servicePushState Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:11 hi-res-audio volumio[1067]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Holding Back the Years","artist":"Bossanatics, Ana Costa","album":"Best of Bossa Lounge (International Version)","albumart":"https://i.scdn.co/image/ab67616d00001e02fc9b95064dbb61964c2de1d2","uri":"spotify:track:1lzUIyI4UqUVzKXsRtBcbU","trackType":"spotify","seek":0,"duration":259,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 16 19:16:11 hi-res-audio volumio[1067]: verbose: CURRENT POSITION 0 Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreStateMachine::syncState stateService play Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreStateMachine::syncState currentStatus play Oct 16 19:16:11 hi-res-audio volumio[1067]: info: Received an update from plugin. extracting info from payload Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreStateMachine::pushState Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioPushState Oct 16 19:16:11 hi-res-audio volumio[1067]: info: MRS: Pushing multiroomSync output update for this device Oct 16 19:16:11 hi-res-audio volumio[1067]: info: MRS: Pushing multiroomSync output Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreStateMachine::pushState Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioPushState Oct 16 19:16:11 hi-res-audio volumio[1067]: info: MRS: Pushing multiroomSync output update for this device Oct 16 19:16:11 hi-res-audio volumio[1067]: info: MRS: Pushing multiroomSync output Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CoreCommandRouter::volumioGetState Oct 16 19:16:11 hi-res-audio volumio[1067]: info: CorePlayQueue::getTrack 0 Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: SPOTIFY VOLUME 55 Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: VOLUMIO VOLUME 56 Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: SPOTIFY VOLUME 55 Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: VOLUMIO VOLUME 56 Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 16 19:16:11 hi-res-audio volumio[1067]: info: MRS: Updating multiroomSync output Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3soyaJP7jQ6FejBSmTv8tg","service":"spop","name":"Last Train to London (feat. Stephenie Coker)","artist":"Costa Azul Quartet","album":"Best of Bossa Lounge (International Version)","type":"song","duration":255,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc9b95064dbb61964c2de1d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0mcqmtwFM1nxvSlLmZtp0M","service":"spop","name":"Me and Mrs. Jones","artist":"Andre Sarbib","album":"Best of Bossa Lounge (International Version)","type":"song","duration":357,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc9b95064dbb61964c2de1d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:16:11 hi-res-audio volumio[1067]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0ar8BJkBnTlWWHcDsKQreD","service":"spop","name":"London Calling","artist":"Diana Martinez","album":"Best of Bossa Lounge (International Version)","type":"song","duration":243,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc9b95064dbb61964c2de1d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 16 19:16:14 hi-res-audio volumio[1067]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 19:16:14 hi-res-audio volumio[1067]: Error: getaddrinfo ENOTFOUND lastfm.freetls.fastly.net Oct 16 19:16:14 hi-res-audio volumio[1067]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Oct 16 19:16:14 hi-res-audio volumio[1067]: errno: -3007, Oct 16 19:16:14 hi-res-audio volumio[1067]: code: 'ENOTFOUND', Oct 16 19:16:14 hi-res-audio volumio[1067]: syscall: 'getaddrinfo', Oct 16 19:16:14 hi-res-audio volumio[1067]: hostname: 'lastfm.freetls.fastly.net' Oct 16 19:16:14 hi-res-audio volumio[1067]: } Oct 16 19:16:14 hi-res-audio volumio[1067]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 19:16:14 hi-res-audio sudo[3774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 19:15 Oct 16 19:16:14 hi-res-audio sudo[3774]: 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"