-- Logs begin at Sun 2025-06-08 03:19:09 +04, end at Wed 2025-06-11 05:02:56 +04. -- Jun 11 05:01:03 volumioavi volumio[9359]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.795&uuid=526f080edc611caef7a9ca7b3603a62d" http://updates.volumio.org/downloader-v1/track-device Jun 11 05:01:03 volumioavi volumio[9359]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 11 05:01:03 volumioavi volumio[9359]: Dload Upload Total Spent Left Speed Jun 11 05:01:03 volumioavi volumio[9359]: [2.2K blob data] Jun 11 05:01:03 volumioavi volumio[9359]: retrying in 5 seconds, trial 0 Jun 11 05:01:03 volumioavi volumio[9359]: info: Volumio Calling Home Jun 11 05:01:15 volumioavi volumio[9359]: error: MyVolumio Plugin failed to start in a timely fashion Jun 11 05:01:15 volumioavi volumio[9359]: [Metrics] CommandRouter: 58s 35.01ms Jun 11 05:01:15 volumioavi volumio[9359]: info: CoreCommandRouter::volumiosetStartupVolume Jun 11 05:01:15 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:01:15 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:01:15 volumioavi volumio[9359]: info: CoreCommandRouter::Close All Modals sent Jun 11 05:01:15 volumioavi volumio[9359]: info: CoreCommandRouter::Close All Modals sent Jun 11 05:01:16 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 11 05:01:16 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 05:01:16 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 11 05:01:20 volumioavi ntpd[806]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 11 05:01:21 volumioavi volumio[9359]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (e7qem0abo45ipo5bl0h42vm8vn): Jun 11 05:01:21 volumioavi volumio[9359]: (ConnectionError) (YouTube) Connection error in getting lounge token Jun 11 05:01:21 volumioavi volumio[9359]: Error info: { Jun 11 05:01:21 volumioavi volumio[9359]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Jun 11 05:01:21 volumioavi volumio[9359]: } Jun 11 05:01:21 volumioavi volumio[9359]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo ENOTFOUND www.youtube.com Jun 11 05:01:21 volumioavi volumio[9359]: Stack trace: Jun 11 05:01:21 volumioavi volumio[9359]: ConnectionError: (YouTube) Connection error in getting lounge token Jun 11 05:01:21 volumioavi volumio[9359]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Jun 11 05:01:21 volumioavi volumio[9359]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jun 11 05:01:21 volumioavi volumio[9359]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Jun 11 05:01:21 volumioavi volumio[9359]: at async Promise.all (index 0) Jun 11 05:01:21 volumioavi volumio[9359]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jun 11 05:01:21 volumioavi volumio[9359]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jun 11 05:01:21 volumioavi volumio[9359]: Going to generate fresh screen Id and try again... Jun 11 05:01:21 volumioavi volumio[9359]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (lc8a8bqa70jr9icppotokelr21): Jun 11 05:01:21 volumioavi volumio[9359]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Jun 11 05:01:21 volumioavi volumio[9359]: Error info: { Jun 11 05:01:21 volumioavi volumio[9359]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Jun 11 05:01:21 volumioavi volumio[9359]: } Jun 11 05:01:21 volumioavi volumio[9359]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo ENOTFOUND www.youtube.com Jun 11 05:01:21 volumioavi volumio[9359]: Stack trace: Jun 11 05:01:21 volumioavi volumio[9359]: ConnectionError: (YouTube Music) Connection error in getting lounge token Jun 11 05:01:21 volumioavi volumio[9359]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Jun 11 05:01:21 volumioavi volumio[9359]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Jun 11 05:01:21 volumioavi volumio[9359]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Jun 11 05:01:21 volumioavi volumio[9359]: at async Promise.all (index 1) Jun 11 05:01:21 volumioavi volumio[9359]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Jun 11 05:01:21 volumioavi volumio[9359]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Jun 11 05:01:21 volumioavi volumio[9359]: Going to generate fresh screen Id and try again... Jun 11 05:01:22 volumioavi volumio[9359]: info: BOOT COMPLETED Jun 11 05:01:25 volumioavi go-librespot[9780]: time="2025-06-11T05:01:25+04:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jun 11 05:01:25 volumioavi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 11 05:01:25 volumioavi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 11 05:01:28 volumioavi systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 11 05:01:28 volumioavi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jun 11 05:01:28 volumioavi systemd[1]: Stopped go-librespot Daemon. Jun 11 05:01:28 volumioavi systemd[1]: Started go-librespot Daemon. Jun 11 05:01:28 volumioavi go-librespot[9842]: Librespot-go daemon starting... Jun 11 05:01:28 volumioavi go-librespot[9842]: time="2025-06-11T05:01:28+04:00" level=info msg="generated new device id: 61d4eb9bdafb3040e686082c47844b0f7bb5a0c6" Jun 11 05:01:28 volumioavi go-librespot[9842]: time="2025-06-11T05:01:28+04:00" level=debug msg="stored credentials found for tjh431buwf471lmsrkm5umbes" Jun 11 05:01:41 volumioavi volumio[9359]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.795&uuid=526f080edc611caef7a9ca7b3603a62d" http://updates.volumio.org/downloader-v1/track-device Jun 11 05:01:41 volumioavi volumio[9359]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 11 05:01:41 volumioavi volumio[9359]: Dload Upload Total Spent Left Speed Jun 11 05:01:41 volumioavi volumio[9359]: [2.2K blob data] Jun 11 05:01:41 volumioavi volumio[9359]: retrying in 5 seconds, trial 1 Jun 11 05:01:41 volumioavi volumio[9359]: info: Volumio Calling Home Jun 11 05:01:48 volumioavi ntpd[806]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 11 05:01:49 volumioavi go-librespot[9842]: time="2025-06-11T05:01:49+04:00" level=debug msg="new websocket client" Jun 11 05:01:49 volumioavi volumio[9359]: info: Connection to go-librespot Websocket established Jun 11 05:01:52 volumioavi volumio[9359]: info: Getting Spotify volume Jun 11 05:01:56 volumioavi go-librespot[9842]: time="2025-06-11T05:01:56+04:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp: lookup clienttoken.spotify.com: device or resource busy" Jun 11 05:01:56 volumioavi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 11 05:01:56 volumioavi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 11 05:01:56 volumioavi volumio[9359]: (node:9359) UnhandledPromiseRejectionWarning: Error: socket hang up Jun 11 05:01:56 volumioavi volumio[9359]: at connResetException (internal/errors.js:607:14) Jun 11 05:01:56 volumioavi volumio[9359]: at Socket.socketOnEnd (_http_client.js:493:23) Jun 11 05:01:56 volumioavi volumio[9359]: at Socket.emit (events.js:327:22) Jun 11 05:01:56 volumioavi volumio[9359]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 11 05:01:56 volumioavi volumio[9359]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 11 05:01:56 volumioavi volumio[9359]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 11 05:01:56 volumioavi volumio[9359]: (node:9359) 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) Jun 11 05:01:56 volumioavi volumio[9359]: (node:9359) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Jun 11 05:01:56 volumioavi volumio[9359]: info: Connection to go-librespot Websocket closed Jun 11 05:01:59 volumioavi volumio[9359]: info: Initializing connection to go-librespot Websocket Jun 11 05:01:59 volumioavi systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 11 05:01:59 volumioavi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jun 11 05:01:59 volumioavi systemd[1]: Stopped go-librespot Daemon. Jun 11 05:01:59 volumioavi systemd[1]: Started go-librespot Daemon. Jun 11 05:01:59 volumioavi go-librespot[9951]: Librespot-go daemon starting... Jun 11 05:01:59 volumioavi go-librespot[9951]: time="2025-06-11T05:01:59+04:00" level=info msg="generated new device id: 0fcfe33725821e24e7997d4e2fe03ac9007bd301" Jun 11 05:01:59 volumioavi go-librespot[9951]: time="2025-06-11T05:01:59+04:00" level=debug msg="stored credentials found for tjh431buwf471lmsrkm5umbes" Jun 11 05:01:59 volumioavi volumiossh-tunnel[16987]: Timeout, server as2.myvolumio.org not responding. Jun 11 05:01:59 volumioavi autossh[16988]: ssh exited with error status 255; restarting ssh Jun 11 05:01:59 volumioavi autossh[16988]: starting ssh (count 3) Jun 11 05:01:59 volumioavi autossh[16988]: ssh child pid is 9958 Jun 11 05:02:04 volumioavi volumio[9359]: verbose: New Socket.io Connection to 192.168.115.3:3000 from 192.168.115.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 11 05:02:04 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 11 05:02:04 volumioavi volumio[9359]: info: CoreCommandRouter::volumioGetState Jun 11 05:02:04 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:08 volumioavi go-librespot[9951]: time="2025-06-11T05:02:08+04:00" level=debug msg="obtained new client token: AAC5Zg6F6oUClfjrh3MxP8OnzFi95Joxb0+oz3vx+IsEI8kmaj5ebcPAKI3I3JercUpOY3k43uQBNo12sS7hUVgZbm+SE/8z6M2+Bv06WQEt90WyMakeA7sce6DevyoibJ/Jos70P4xhDpva0d3IduvZ81YXhDCfMR7dvgpyjCsU6y9TouYTT8z7wliQm3qaBUq2dxVGGRYP6qO2XChhFZnaShNfogGWGE+XVrTQp4EgHT4j/rnymvLJldPWXA==" Jun 11 05:02:08 volumioavi volumiossh-tunnel[16987]: Warning: Permanently added '[as2.myvolumio.org]:2222,[165.232.189.9]:2222' (RSA) to the list of known hosts. Jun 11 05:02:08 volumioavi go-librespot[9951]: time="2025-06-11T05:02:08+04: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jun 11 05:02:08 volumioavi volumio[9359]: info: Discovery: adding 06e39b70-c61b-428c-96af-9ac745155797 Jun 11 05:02:08 volumioavi volumio[9359]: info: Discovery: Found device VolumioAvi Jun 11 05:02:08 volumioavi volumio[9359]: info: CoreCommandRouter::volumioGetState Jun 11 05:02:08 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:08 volumioavi volumio[9359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 11 05:02:08 volumioavi go-librespot[9951]: time="2025-06-11T05:02:08+04:00" level=debug msg="completed keyexchange" Jun 11 05:02:08 volumioavi volumiossh-tunnel[16987]: Warning: remote port forwarding failed for listen port 8091 Jun 11 05:02:09 volumioavi go-librespot[9951]: time="2025-06-11T05:02:09+04:00" level=debug msg="completed challenge" Jun 11 05:02:09 volumioavi go-librespot[9951]: time="2025-06-11T05:02:09+04:00" level=debug msg="authenticated as tjh431buwf471lmsrkm5umbes" Jun 11 05:02:09 volumioavi go-librespot[9951]: time="2025-06-11T05:02:09+04:00" level=debug msg="authenticated as tjh431buwf471lmsrkm5umbes" Jun 11 05:02:10 volumioavi go-librespot[9951]: time="2025-06-11T05:02:10+04:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 11 05:02:10 volumioavi volumio[9359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 11 05:02:10 volumioavi go-librespot[9951]: time="2025-06-11T05:02:10+04:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 11 05:02:10 volumioavi go-librespot[9951]: time="2025-06-11T05:02:10+04:00" level=debug msg="dealer connection opened" Jun 11 05:02:10 volumioavi go-librespot[9951]: time="2025-06-11T05:02:10+04:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 11 05:02:10 volumioavi go-librespot[9951]: time="2025-06-11T05:02:10+04:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 11 05:02:10 volumioavi go-librespot[9951]: time="2025-06-11T05:02:10+04:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 11 05:02:10 volumioavi go-librespot[9951]: time="2025-06-11T05:02:10+04:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 11 05:02:10 volumioavi go-librespot[9951]: time="2025-06-11T05:02:10+04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 11 05:02:10 volumioavi go-librespot[9951]: time="2025-06-11T05:02:10+04:00" level=debug msg="received connection id: YTlmODJlNzMtNzIxMy00ODE4LTk2OWUtNzRlMGJjZGExYWU0K2RlYWxlcit0Y3A6Ly8wYWNhNDI1NS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArRkM0RDRBNkFDMDY0RjRCRTNBRTQxNjhCODQ5OEFFM0RCODVFMTJDQkJCNjBBQjBGOTMxMzUwODUyQzVFMUZENg==" Jun 11 05:02:10 volumioavi go-librespot[9951]: time="2025-06-11T05:02:10+04:00" level=debug msg="put connect state because NEW_DEVICE" Jun 11 05:02:12 volumioavi volumio[9359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 11 05:02:17 volumioavi volumio[9359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 11 05:02:17 volumioavi go-librespot[9951]: time="2025-06-11T05:02:17+04:00" level=debug msg="new websocket client" Jun 11 05:02:17 volumioavi volumio[9359]: info: Connection to go-librespot Websocket established Jun 11 05:02:18 volumioavi ntpd[806]: Soliciting pool server 2001:ac8:81:65:0:2:0:3 Jun 11 05:02:18 volumioavi volumio[9359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 11 05:02:18 volumioavi volumio[9359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 11 05:02:18 volumioavi volumio[9359]: info: CoreCommandRouter::volumioGetState Jun 11 05:02:18 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:18 volumioavi volumio[9359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jun 11 05:02:18 volumioavi volumio[9359]: SPOTIFY: SPOTIFY VOLUME undefined Jun 11 05:02:18 volumioavi volumio[9359]: SPOTIFY: VOLUMIO VOLUME 86 Jun 11 05:02:18 volumioavi volumio[9359]: info: Aligning Spotify Volume to Volumio Volume Jun 11 05:02:18 volumioavi volumio[9359]: info: CoreCommandRouter::volumioGetState Jun 11 05:02:18 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:18 volumioavi volumio[9359]: info: Setting Spotify Volume from Volumio: 86 Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 11 05:02:19 volumioavi volumio[9359]: info: Adding plugin bluetooth to MyMusic Plugins Jun 11 05:02:19 volumioavi volumio[9359]: info: Adding plugin multiroom to MyMusic Plugins Jun 11 05:02:19 volumioavi volumio[9359]: info: Adding plugin metavolumio to MyMusic Plugins Jun 11 05:02:19 volumioavi volumio[9359]: info: Adding plugin cd_controller to MyMusic Plugins Jun 11 05:02:19 volumioavi volumio[9359]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 11 05:02:19 volumioavi volumio[9359]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 11 05:02:19 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 11 05:02:20 volumioavi volumio[9359]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 11 05:02:20 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 11 05:02:20 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:20 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:20 volumioavi volumio[9359]: info: Starting MyVolumio Remote Streaming Endpoints Jun 11 05:02:20 volumioavi volumio[9359]: info: MyVolumio login type: Token Jun 11 05:02:20 volumioavi volumio[9359]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 11 05:02:20 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 11 05:02:20 volumioavi volumio[9359]: info: Starting Streaming Service Transparent Proxy Jun 11 05:02:20 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 11 05:02:20 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 11 05:02:20 volumioavi volumio[9359]: info: Streaming services startup Jun 11 05:02:20 volumioavi volumio[9359]: info: Starting Streaming Daemon Jun 11 05:02:20 volumioavi sudo[10002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 11 05:02:20 volumioavi sudo[10002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:20 volumioavi sudo[10002]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:20 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 11 05:02:20 volumioavi volumio[9359]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.795&uuid=526f080edc611caef7a9ca7b3603a62d" http://updates.volumio.org/downloader-v1/track-device Jun 11 05:02:20 volumioavi volumio[9359]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 11 05:02:20 volumioavi volumio[9359]: Dload Upload Total Spent Left Speed Jun 11 05:02:20 volumioavi volumio[9359]: [2.2K blob data] Jun 11 05:02:20 volumioavi volumio[9359]: retrying in 5 seconds, trial 2 Jun 11 05:02:20 volumioavi volumio[9359]: info: Volumio Calling Home Jun 11 05:02:21 volumioavi volumio[9359]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Jun 11 05:02:21 volumioavi volumio[9359]: info: Sending Spotify command with payload to local API: /player/volume Jun 11 05:02:21 volumioavi volumio[9359]: info: Getting Spotify volume Jun 11 05:02:21 volumioavi go-librespot[9951]: time="2025-06-11T05:02:21+04:00" level=debug msg="update volume to 56360/65535" Jun 11 05:02:21 volumioavi volumio[9359]: error: Cannot start Volumio Streaming Daemon Jun 11 05:02:21 volumioavi volumio[9359]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 11 05:02:21 volumioavi volumio[9359]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 11 05:02:21 volumioavi volumio[9359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 11 05:02:21 volumioavi volumio[9359]: info: CoreCommandRouter::volumioGetState Jun 11 05:02:21 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:21 volumioavi volumio[9359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jun 11 05:02:21 volumioavi go-librespot[9951]: time="2025-06-11T05:02:21+04:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 11 05:02:21 volumioavi go-librespot[9951]: time="2025-06-11T05:02:21+04:00" level=trace msg="emitting websocket event: volume" Jun 11 05:02:21 volumioavi volumio[9359]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Jun 11 05:02:21 volumioavi volumio[9359]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Jun 11 05:02:21 volumioavi volumio[9359]: info: Spotify volume: 86 Jun 11 05:02:21 volumioavi volumio[9359]: STREAMING PROXY: Starting server on port 3245 Jun 11 05:02:21 volumioavi volumio[9359]: Node JS runtime: 14 Jun 11 05:02:21 volumioavi volumio[9359]: error: MyVolumio Custom Token format not valid, refreshing it Jun 11 05:02:21 volumioavi volumio[9359]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 11 05:02:21 volumioavi volumio[9359]: info: Volumio called home Jun 11 05:02:23 volumioavi volumio[9359]: info: MyVolumio login type: Token Jun 11 05:02:23 volumioavi volumio[9359]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 11 05:02:24 volumioavi volumio[9359]: info: MyVolumio token set successfully Jun 11 05:02:24 volumioavi volumio[9359]: info: MYVOLUMIO: Adding device Jun 11 05:02:24 volumioavi volumio[9359]: info: MYVOLUMIO: Evaluating Server Jun 11 05:02:25 volumioavi volumio[9359]: info: MyVolumio Plan changed: premium Jun 11 05:02:25 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 11 05:02:25 volumioavi volumio[9359]: info: Removing browser output: myVolumio user plan is not superstar Jun 11 05:02:25 volumioavi volumio[9359]: info: Removing audio output: Jun 11 05:02:25 volumioavi volumio[9359]: info: MYVOLUMIO: Adding device Jun 11 05:02:25 volumioavi volumio[9359]: info: MYVOLUMIO: Evaluating Server Jun 11 05:02:25 volumioavi volumio[9359]: info: Remote config written successfully Jun 11 05:02:25 volumioavi volumio[9359]: info: Starting Tunnel 1 Jun 11 05:02:25 volumioavi volumio[9359]: info: Starting Tunnel Connection Checker Jun 11 05:02:25 volumioavi volumio[9359]: info: MYVolumio Device enabled Jun 11 05:02:25 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 11 05:02:25 volumioavi volumio[9359]: info: MyVolumio status changed Jun 11 05:02:25 volumioavi volumio[9359]: info: Streaming services startup Jun 11 05:02:25 volumioavi volumio[9359]: info: Starting Streaming Daemon Jun 11 05:02:25 volumioavi sudo[10045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 11 05:02:25 volumioavi sudo[10045]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:25 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 11 05:02:25 volumioavi sudo[10045]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:25 volumioavi volumio[9359]: error: [MyVolumio PluginManager] Cache data is invalid! Jun 11 05:02:25 volumioavi volumio[9359]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.115.224","name":"LMS","ver":"9.0.1","uuid":"0cc9d0f4-00c8-4426-869c-243a6e2b7975","jsonPort":"9000","cliPort":"9090"} Jun 11 05:02:25 volumioavi volumio[9359]: error: Cannot start Volumio Streaming Daemon Jun 11 05:02:25 volumioavi volumio[9359]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 11 05:02:25 volumioavi volumio[9359]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 11 05:02:26 volumioavi volumio[9359]: info: Setting Geolocation for MyVolumio to as2 Jun 11 05:02:26 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:26 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:26 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:26 volumioavi volumio[9359]: info: Setting Geolocation for MyVolumio to as2 Jun 11 05:02:26 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:26 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:26 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:26 volumioavi volumio[9359]: info: Successfully Added MyVolumio device Jun 11 05:02:27 volumioavi volumio[9359]: info: [squeezelite_mc] Notification listener started Jun 11 05:02:27 volumioavi volumio[9359]: info: [squeezelite_mc] Getting players connected to LMS (192.168.115.224) Jun 11 05:02:27 volumioavi volumio[9359]: info: [squeezelite_mc] Players connected to LMS (192.168.115.224): [{"id":"40:62:31:12:68:76","uuid":null,"ip":"192.168.115.101","name":"MC-VolumioTom","server":{"ip":"192.168.115.224","name":"LMS","ver":"9.0.1","uuid":"0cc9d0f4-00c8-4426-869c-243a6e2b7975","jsonPort":"9000","cliPort":"9090"}},{"id":"d8:3a:dd:d9:e6:d3","uuid":null,"ip":"192.168.115.3","name":"MC-VolumioAvi","server":{"ip":"192.168.115.224","name":"LMS","ver":"9.0.1","uuid":"0cc9d0f4-00c8-4426-869c-243a6e2b7975","jsonPort":"9000","cliPort":"9090"}}] Jun 11 05:02:27 volumioavi volumio[9359]: info: [squeezelite_mc] Player found: {"id":"d8:3a:dd:d9:e6:d3","uuid":null,"ip":"192.168.115.3","name":"MC-VolumioAvi","server":{"ip":"192.168.115.224","name":"LMS","ver":"9.0.1","uuid":"0cc9d0f4-00c8-4426-869c-243a6e2b7975","jsonPort":"9000","cliPort":"9090"}} Jun 11 05:02:27 volumioavi volumio[9359]: info: Updating MyVolumio device info Jun 11 05:02:27 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:27 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:27 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:27 volumioavi volumio[9359]: info: Updating MyVolumio device info Jun 11 05:02:27 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:27 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:27 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:27 volumioavi volumio[9359]: info: Successfully Updated MyVolumio device Jun 11 05:02:27 volumioavi volumio[9359]: info: Successfully Added MyVolumio device Jun 11 05:02:27 volumioavi volumio[9359]: info: Successfully Updated MyVolumio device Jun 11 05:02:28 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Jun 11 05:02:28 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 11 05:02:28 volumioavi volumio[9359]: info: [1749603748126] Starting BluetoothController Jun 11 05:02:28 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 11 05:02:28 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 11 05:02:28 volumioavi sudo[10050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 11 05:02:28 volumioavi sudo[10050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:28 volumioavi sudo[10050]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:28 volumioavi volumio[9359]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Jun 11 05:02:28 volumioavi volumio[9359]: info: MRS: MultiRoom plugin initialized Jun 11 05:02:28 volumioavi volumio[9359]: info: MRS: STOPPING SNAPCLIENT Jun 11 05:02:28 volumioavi volumio[9359]: info: MRS: Snap server stop Jun 11 05:02:28 volumioavi sudo[10080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 11 05:02:28 volumioavi sudo[10080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:28 volumioavi volumio[9359]: info: MRS: STOPPING volumioStreaming Jun 11 05:02:28 volumioavi sudo[10080]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:29 volumioavi sudo[10083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 11 05:02:29 volumioavi sudo[10083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:29 volumioavi sudo[10083]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:29 volumioavi sudo[10086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 11 05:02:29 volumioavi sudo[10086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 11 05:02:29 volumioavi sudo[10086]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:29 volumioavi sudo[10089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 11 05:02:29 volumioavi sudo[10089]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:29 volumioavi sudo[10089]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 11 05:02:29 volumioavi volumio[9359]: info: Preparing to generate the ALSA configuration file Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 11 05:02:29 volumioavi volumio[9359]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 11 05:02:29 volumioavi volumio[9359]: info: Reading ALSA contributions from plugins. Jun 11 05:02:29 volumioavi volumio[9359]: info: MRS: Removed streaming files Jun 11 05:02:29 volumioavi volumio[9359]: info: MRS: volumioStreaming STOPPED Jun 11 05:02:29 volumioavi volumio[9359]: info: MRS: SNAPSERVER STOPPED Jun 11 05:02:29 volumioavi volumio[9359]: info: MRS: SNAPCLIENT STOPPED Jun 11 05:02:29 volumioavi volumio[9359]: info: Asound.conf file written Jun 11 05:02:29 volumioavi sudo[10092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 11 05:02:29 volumioavi sudo[10092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:29 volumioavi sudo[10092]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:29 volumioavi volumio[9359]: info: Output device has changed, restarting MPD Jun 11 05:02:29 volumioavi sudo[10097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 05:02:29 volumioavi sudo[10097]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:29 volumioavi sudo[10097]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:29 volumioavi volumio[9359]: info: Output device has changed, restarting Shairport Sync Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:29 volumioavi sudo[10100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 05:02:29 volumioavi sudo[10100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:29 volumioavi systemd[1]: Stopping Music Player Daemon... Jun 11 05:02:29 volumioavi systemd[1]: mpd.service: Succeeded. Jun 11 05:02:29 volumioavi volumio[9359]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 05:02:29 volumioavi systemd[1]: Stopped Music Player Daemon. Jun 11 05:02:29 volumioavi volumio[9359]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 11 05:02:29 volumioavi systemd[1]: Starting Music Player Daemon... Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 11 05:02:29 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output for this device Jun 11 05:02:29 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding audio output: Jun 11 05:02:29 volumioavi sudo[10109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name VolumioAvi Jun 11 05:02:29 volumioavi sudo[10109]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:29 volumioavi volumio[9359]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi sudo[10109]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding METAVOLUMIO REST API Endpoints Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jun 11 05:02:29 volumioavi volumio[9359]: info: Preparing CD Folders Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding CD REST API Endpoints Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 11 05:02:29 volumioavi volumio[9359]: info: Starting UDEV Watcher for CD Jun 11 05:02:29 volumioavi volumio[9359]: info: Detecting CD presence with UDEV Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jun 11 05:02:29 volumioavi sudo[10105]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 11 05:02:29 volumioavi sudo[10105]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:29 volumioavi sudo[10105]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding inputs REST Endpoints Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jun 11 05:02:29 volumioavi volumio[9359]: info: Scanning Audio Inputs Jun 11 05:02:29 volumioavi volumio[9359]: info: Checking against Known Cards name Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 05:02:29 volumioavi volumio[9359]: info: [1749603749600] CoreMusicLibrary::Adding element HiFiBerry ADC Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 05:02:29 volumioavi volumio[9359]: Cannot find translation for source Bandcamp Discover Jun 11 05:02:29 volumioavi volumio[9359]: Cannot find translation for source Calm Radio Jun 11 05:02:29 volumioavi volumio[9359]: Cannot find translation for source Mixcloud Jun 11 05:02:29 volumioavi volumio[9359]: Cannot find translation for source SoundCloud Jun 11 05:02:29 volumioavi volumio[9359]: Cannot find translation for source YouTube2 Jun 11 05:02:29 volumioavi volumio[9359]: Cannot find translation for source YouTube Music Jun 11 05:02:29 volumioavi volumio[9359]: Cannot find translation for source HiFiBerry ADC Jun 11 05:02:29 volumioavi volumio[9359]: info: Checking against Known Cards name Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding Server instance for streaming Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jun 11 05:02:29 volumioavi volumio[9359]: error: Hi Res Audio Failed Login: Missing Login Data Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 11 05:02:29 volumioavi volumio[9359]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jun 11 05:02:29 volumioavi volumio[9359]: info: Refreshing TIDAL token Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jun 11 05:02:29 volumioavi volumio[9359]: info: Refreshing QOBUZ token Jun 11 05:02:29 volumioavi volumio[9359]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jun 11 05:02:29 volumioavi sudo[10119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 05:02:29 volumioavi sudo[10119]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:29 volumioavi volumio[9359]: info: Bluetooth name changed to VolumioAvi Jun 11 05:02:29 volumioavi volumio[9359]: info: MPD Permissions set Jun 11 05:02:29 volumioavi systemd[1]: Stopping Volumio Tidal Connect Service... Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::volumioGetState Jun 11 05:02:29 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:29 volumioavi systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 11 05:02:29 volumioavi systemd[1]: vtcs.service: Succeeded. Jun 11 05:02:29 volumioavi systemd[1]: Stopped Volumio Tidal Connect Service. Jun 11 05:02:29 volumioavi sudo[10119]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:29 volumioavi volumio[9359]: info: Starting Shairport Sync Jun 11 05:02:29 volumioavi sudo[10131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 05:02:29 volumioavi sudo[10131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:29 volumioavi volumio[9359]: info: CoreCommandRouter::volumioGetState Jun 11 05:02:29 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:29 volumioavi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 05:02:29 volumioavi systemd[1]: shairport-sync.service: Succeeded. Jun 11 05:02:29 volumioavi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 11 05:02:29 volumioavi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 05:02:29 volumioavi sudo[10131]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:29 volumioavi volumio[9359]: info: Shairport-Sync Started Jun 11 05:02:30 volumioavi mpd[10115]: Jun 11 05:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 11 05:02:30 volumioavi systemd[1]: Started Music Player Daemon. Jun 11 05:02:30 volumioavi sudo[10100]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:30 volumioavi volumio[9359]: error: updateQueue error: null Jun 11 05:02:30 volumioavi sudo[10143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 11 05:02:30 volumioavi sudo[10143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:30 volumioavi autossh[16988]: received signal to exit (15) Jun 11 05:02:30 volumioavi systemd[1]: Stopping MyVolumio SSH Tunnel... Jun 11 05:02:30 volumioavi systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Jun 11 05:02:30 volumioavi systemd[1]: sshtunnel.service: Succeeded. Jun 11 05:02:30 volumioavi systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 11 05:02:30 volumioavi systemd[1]: Started MyVolumio SSH Tunnel. Jun 11 05:02:30 volumioavi sudo[10143]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:30 volumioavi volumio[9359]: info: Remote SSH Started Jun 11 05:02:30 volumioavi autossh[10146]: port set to 0, monitoring disabled Jun 11 05:02:30 volumioavi autossh[10146]: starting ssh (count 1) Jun 11 05:02:30 volumioavi autossh[10146]: ssh child pid is 10149 Jun 11 05:02:30 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:30 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:30 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:30 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 05:02:31 volumioavi volumiossh-tunnel[10145]: Warning: Permanently added '[as2.myvolumio.org]:2222,[165.232.189.9]:2222' (RSA) to the list of known hosts. Jun 11 05:02:31 volumioavi volumio[9359]: info: MRS: Found cast device: Google-TV-Streamer-d93f5c4db63cf55ec2da4b0de2891cf5 Jun 11 05:02:31 volumioavi volumio[9359]: info: Adding audio output: Jun 11 05:02:31 volumioavi volumio[9359]: info: MRS: Found cast device: SHIELD-Android-TV-a005a5a23c7cd054e50c37b20429ba26 Jun 11 05:02:31 volumioavi volumio[9359]: info: Adding audio output: Jun 11 05:02:31 volumioavi volumio[9359]: info: MRS: Found cast device: SHIELD-Android-TV-813580c23a3cb9b61f5247fe1953a058 Jun 11 05:02:31 volumioavi volumio[9359]: info: Adding audio output: Jun 11 05:02:31 volumioavi volumio[9359]: info: MRS: Found cast device: SHIELD-Android-TV-919673422dec9680cdee7c7876e933ff Jun 11 05:02:31 volumioavi volumio[9359]: info: Adding audio output: Jun 11 05:02:31 volumioavi volumio[9359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 11 05:02:31 volumioavi volumio[9359]: info: CoreCommandRouter::volumioGetState Jun 11 05:02:31 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:31 volumioavi volumio[9359]: info: Access Token successfully retrieved Jun 11 05:02:31 volumioavi volumio[9359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 05:02:31 volumioavi volumio[9359]: info: [1749603751870] CoreMusicLibrary::Adding element QOBUZ Jun 11 05:02:31 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 05:02:31 volumioavi volumio[9359]: Cannot find translation for source Bandcamp Discover Jun 11 05:02:31 volumioavi volumio[9359]: Cannot find translation for source Calm Radio Jun 11 05:02:31 volumioavi volumio[9359]: Cannot find translation for source Mixcloud Jun 11 05:02:31 volumioavi volumio[9359]: Cannot find translation for source SoundCloud Jun 11 05:02:31 volumioavi volumio[9359]: Cannot find translation for source YouTube2 Jun 11 05:02:31 volumioavi volumio[9359]: Cannot find translation for source YouTube Music Jun 11 05:02:31 volumioavi volumio[9359]: Cannot find translation for source HiFiBerry ADC Jun 11 05:02:31 volumioavi volumio[9359]: Cannot find translation for source QOBUZ Jun 11 05:02:31 volumioavi volumio[9359]: info: Stopping AccessToken refresher cron for QOBUZ Jun 11 05:02:31 volumioavi volumio[9359]: info: AccessToken refresher cron started for QOBUZ Jun 11 05:02:31 volumioavi volumio[9359]: info: Adding QOBUZ REST API Endpoints Jun 11 05:02:31 volumioavi volumio[9359]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jun 11 05:02:32 volumioavi sudo[10151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 11 05:02:32 volumioavi sudo[10151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:32 volumioavi sudo[10151]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:32 volumioavi volumio[9359]: info: Volumio BT Module successfully started Jun 11 05:02:32 volumioavi volumio[9359]: info: TidalConnect service stoped! Jun 11 05:02:32 volumioavi volumio[9359]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 11 05:02:32 volumioavi volumio[9359]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 11 05:02:32 volumioavi sudo[10162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 11 05:02:32 volumioavi sudo[10162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 05:02:32 volumioavi systemd[1]: Started Volumio Tidal Connect Service. Jun 11 05:02:32 volumioavi sudo[10162]: pam_unix(sudo:session): session closed for user root Jun 11 05:02:33 volumioavi volumio[9359]: info: Executing endpoint tc_getconfig Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 11 05:02:33 volumioavi vtcs[10164]: STARTING TidalConnect services, version: 1.4.0.34 Jun 11 05:02:33 volumioavi vtcs[10164]: STARTED TidalConnect services. Jun 11 05:02:33 volumioavi volumio[9359]: info: Executing endpoint tc_connect Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 11 05:02:33 volumioavi volumio[9359]: info: Connecting to TidalConnect Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::servicePushState Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreStateMachine::pushState Jun 11 05:02:33 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::volumioPushState Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output update for this device Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output Jun 11 05:02:33 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:33 volumioavi volumio[9359]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jun 11 05:02:33 volumioavi volumio[9359]: verbose: CURRENT POSITION 0 Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreStateMachine::syncState stateService stop Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreStateMachine::syncState currentStatus stop Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreStateMachine::pushState Jun 11 05:02:33 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::volumioPushState Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output update for this device Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output Jun 11 05:02:33 volumioavi volumio[9359]: info: No code Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreStateMachine::pushState Jun 11 05:02:33 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::volumioPushState Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output update for this device Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::servicePushState Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreStateMachine::pushState Jun 11 05:02:33 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::volumioPushState Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output update for this device Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output Jun 11 05:02:33 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:33 volumioavi volumio[9359]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jun 11 05:02:33 volumioavi volumio[9359]: verbose: CURRENT POSITION 0 Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreStateMachine::syncState stateService stop Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreStateMachine::syncState currentStatus stop Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreStateMachine::pushState Jun 11 05:02:33 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::volumioPushState Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output update for this device Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output Jun 11 05:02:33 volumioavi volumio[9359]: info: No code Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreStateMachine::pushState Jun 11 05:02:33 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:33 volumioavi volumio[9359]: info: CoreCommandRouter::volumioPushState Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output update for this device Jun 11 05:02:33 volumioavi volumio[9359]: info: MRS: Pushing multiroomSync output Jun 11 05:02:33 volumioavi volumio[9359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jun 11 05:02:33 volumioavi volumio[9359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jun 11 05:02:33 volumioavi volumio[9359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jun 11 05:02:33 volumioavi volumio[9359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jun 11 05:02:33 volumioavi volumio[9359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jun 11 05:02:33 volumioavi volumio[9359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jun 11 05:02:33 volumioavi volumio[9359]: info: Access Token successfully retrieved Jun 11 05:02:33 volumioavi volumio[9359]: info: Successfully retrieved User Session From TIDAL Jun 11 05:02:34 volumioavi volumio[9359]: info: MRS: Getting audio outputs on start Jun 11 05:02:34 volumioavi volumio[9359]: info: MRS: Requesting all other devices output Jun 11 05:02:34 volumioavi volumio[9359]: info: Successfully retrieved User Subscription From TIDAL Jun 11 05:02:34 volumioavi volumio[9359]: info: Adding TIDAL to Browse Sources Jun 11 05:02:34 volumioavi volumio[9359]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 05:02:34 volumioavi volumio[9359]: info: [1749603754706] CoreMusicLibrary::Adding element TIDAL Jun 11 05:02:34 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 05:02:34 volumioavi volumio[9359]: Cannot find translation for source Bandcamp Discover Jun 11 05:02:34 volumioavi volumio[9359]: Cannot find translation for source Calm Radio Jun 11 05:02:34 volumioavi volumio[9359]: Cannot find translation for source Mixcloud Jun 11 05:02:34 volumioavi volumio[9359]: Cannot find translation for source SoundCloud Jun 11 05:02:34 volumioavi volumio[9359]: Cannot find translation for source YouTube2 Jun 11 05:02:34 volumioavi volumio[9359]: Cannot find translation for source YouTube Music Jun 11 05:02:34 volumioavi volumio[9359]: Cannot find translation for source HiFiBerry ADC Jun 11 05:02:34 volumioavi volumio[9359]: Cannot find translation for source QOBUZ Jun 11 05:02:34 volumioavi volumio[9359]: Cannot find translation for source TIDAL Jun 11 05:02:34 volumioavi volumio[9359]: info: Stopping AccessToken refresher cron Jun 11 05:02:34 volumioavi volumio[9359]: info: AccessToken refresher cron started Jun 11 05:02:34 volumioavi volumio[9359]: info: Adding TIDAL REST API Endpoints Jun 11 05:02:34 volumioavi volumio[9359]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jun 11 05:02:35 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 11 05:02:35 volumioavi volumio[9359]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 11 05:02:35 volumioavi volumio[9359]: info: CoreCommandRouter::volumioGetState Jun 11 05:02:35 volumioavi volumio[9359]: info: CorePlayQueue::getTrack 0 Jun 11 05:02:35 volumioavi volumio[9359]: info: TidalConnect service started! Jun 11 05:02:40 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:40 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 05:02:40 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 11 05:02:40 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 11 05:02:40 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 11 05:02:40 volumioavi volumio[9359]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 05:02:40 volumioavi volumio[9359]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 11 05:02:40 volumioavi go-librespot[9951]: time="2025-06-11T05:02:40+04:00" level=error msg="failed receiving dealer message" error="failed to get reader: failed to read frame header: read tcp 192.168.115.3:41376->35.186.224.41:443: read: connection reset by peer" Jun 11 05:02:40 volumioavi go-librespot[9951]: time="2025-06-11T05:02:40+04:00" level=debug msg="re-established dealer connection" Jun 11 05:02:40 volumioavi go-librespot[9951]: time="2025-06-11T05:02:40+04:00" level=debug msg="received connection id: MzA1ZTg2MmMtYjZmMC00NjA1LTgwY2MtYzBjYmQyNWVhNWI4K2RlYWxlcit0Y3A6Ly8wYWNiZDg4OC5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArQ0QyMTRCRDJDN0FERkM3MEZCMkEwMUQyMTA3MDhDNzU1RUQ2QkNFOEJBNDZEMEQ0NDVENUVBMEFEQkQyNTYwNw==" Jun 11 05:02:41 volumioavi go-librespot[9951]: time="2025-06-11T05:02:41+04:00" level=debug msg="put connect state because NEW_DEVICE" Jun 11 05:02:44 volumioavi ntpd[806]: Soliciting pool server 2a13:8a40::368 Jun 11 05:02:51 volumioavi dhcpcd[912]: wlan0: carrier lost Jun 11 05:02:51 volumioavi wpa_supplicant[901]: wlan0: Associated with 60:83:e7:80:a3:cb Jun 11 05:02:51 volumioavi wpa_supplicant[901]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:83:e7:80:a3:cb completed [id=0 id_str=] Jun 11 05:02:51 volumioavi wpa_supplicant[901]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 11 05:02:51 volumioavi avahi-daemon[650]: Withdrawing address record for 192.168.115.3 on wlan0. Jun 11 05:02:51 volumioavi avahi-daemon[650]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.115.3. Jun 11 05:02:51 volumioavi dhcpcd[912]: wlan0: deleting route to 192.168.115.0/24 Jun 11 05:02:51 volumioavi dhcpcd[912]: wlan0: deleting default route via 192.168.115.1 Jun 11 05:02:51 volumioavi avahi-daemon[650]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 11 05:02:51 volumioavi volumio[9359]: info: Discovery: A device disappeared from network Jun 11 05:02:51 volumioavi volumio[9359]: info: Discovery: Device volumioavi disappeared from network Jun 11 05:02:51 volumioavi dhcpcd[912]: wlan0: carrier acquired Jun 11 05:02:51 volumioavi dhcpcd[912]: wlan0: IAID dd:d9:e6:d3 Jun 11 05:02:51 volumioavi dhcpcd[912]: wlan0: probing address 192.168.115.3/24 Jun 11 05:02:51 volumioavi dhcpcd[912]: wlan0: soliciting an IPv6 router Jun 11 05:02:53 volumioavi ntpd[806]: Deleting interface #21 wlan0, 192.168.115.3#123, interface stats: received=1, sent=6, dropped=0, active_time=149 secs Jun 11 05:02:53 volumioavi ntpd[806]: 47.91.111.180 local addr 192.168.115.3 -> Jun 11 05:02:53 volumioavi ntpd[806]: 93.127.181.22 local addr 192.168.115.3 -> Jun 11 05:02:55 volumioavi volumio[9359]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 05:02:55 volumioavi volumio[9359]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 11 05:02:55 volumioavi volumio[9359]: at doSend (dgram.js:692:16) Jun 11 05:02:55 volumioavi volumio[9359]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 11 05:02:55 volumioavi volumio[9359]: at afterDns (dgram.js:638:5) Jun 11 05:02:55 volumioavi volumio[9359]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 11 05:02:55 volumioavi volumio[9359]: errno: -101, Jun 11 05:02:55 volumioavi volumio[9359]: code: 'ENETUNREACH', Jun 11 05:02:55 volumioavi volumio[9359]: syscall: 'send', Jun 11 05:02:55 volumioavi volumio[9359]: address: '255.255.255.255', Jun 11 05:02:55 volumioavi volumio[9359]: port: 3483 Jun 11 05:02:55 volumioavi volumio[9359]: } Jun 11 05:02:55 volumioavi volumio[9359]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 05:02:56 volumioavi dhcpcd[912]: wlan0: using static address 192.168.115.3/24 Jun 11 05:02:56 volumioavi avahi-daemon[650]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.115.3. Jun 11 05:02:56 volumioavi avahi-daemon[650]: New relevant interface wlan0.IPv4 for mDNS. Jun 11 05:02:56 volumioavi dhcpcd[912]: wlan0: adding route to 192.168.115.0/24 Jun 11 05:02:56 volumioavi dhcpcd[912]: wlan0: adding default route via 192.168.115.1 Jun 11 05:02:56 volumioavi avahi-daemon[650]: Registering new address record for 192.168.115.3 on wlan0.IPv4. Jun 11 05:02:56 volumioavi sudo[10311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-11 05:01 Jun 11 05:02:56 volumioavi sudo[10311]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"