-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Tue 2025-06-10 15:41:43 +07. -- Jun 10 15:40:08 volumio go-librespot[1590]: time="2025-06-10T15:40:08+07:00" level=debug msg="fetched chunk 14/17, size: 524288" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Jun 10 15:40:14 volumio go-librespot[1590]: time="2025-06-10T15:40:14+07:00" level=trace msg="sent dealer ping" Jun 10 15:40:14 volumio go-librespot[1590]: time="2025-06-10T15:40:14+07:00" level=trace msg="received dealer pong" Jun 10 15:40:14 volumio volumio-remote-updater[828]: [2025-06-10 15:40:14] [connect] Successful connection Jun 10 15:40:18 volumio ntpd[996]: Soliciting pool server 2401:5b60:0:1::52 Jun 10 15:40:21 volumio go-librespot[1590]: time="2025-06-10T15:40:21+07:00" level=debug msg="fetched chunk 15/17, size: 524288" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Jun 10 15:40:29 volumio volumio-remote-updater[828]: [2025-06-10 15:40:29] [connect] Successful connection Jun 10 15:40:30 volumio ntpd[996]: Soliciting pool server 103.184.124.254 Jun 10 15:40:31 volumio ntpd[996]: Soliciting pool server 115.165.161.155 Jun 10 15:40:34 volumio go-librespot[1590]: time="2025-06-10T15:40:34+07:00" level=debug msg="fetched chunk 16/17, size: 524288" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Jun 10 15:40:38 volumio ntpd[996]: Soliciting pool server 115.165.161.155 Jun 10 15:40:43 volumio go-librespot[1590]: time="2025-06-10T15:40:43+07:00" level=trace msg="received accesspoint ping" Jun 10 15:40:43 volumio go-librespot[1590]: time="2025-06-10T15:40:43+07:00" level=trace msg="received accesspoint pong ack" Jun 10 15:40:44 volumio go-librespot[1590]: time="2025-06-10T15:40:44+07:00" level=trace msg="sent dealer ping" Jun 10 15:40:44 volumio go-librespot[1590]: time="2025-06-10T15:40:44+07:00" level=trace msg="received dealer pong" Jun 10 15:40:44 volumio volumio-remote-updater[828]: [2025-06-10 15:40:44] [connect] Successful connection Jun 10 15:40:46 volumio go-librespot[1590]: time="2025-06-10T15:40:46+07:00" level=debug msg="fetched chunk 17/17, size: 3012" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Jun 10 15:40:54 volumio go-librespot[1590]: time="2025-06-10T15:40:54+07:00" level=debug msg="prefetching next track" uri="spotify:track:1INKoD3iLAGucA61tkCEJt" Jun 10 15:40:54 volumio go-librespot[1590]: time="2025-06-10T15:40:54+07:00" level=debug msg="selected format OGG_VORBIS_320 (28ee07ee20c6eca5442c30b1a000a6908db52892)" uri="spotify:track:1INKoD3iLAGucA61tkCEJt" Jun 10 15:40:54 volumio go-librespot[1590]: time="2025-06-10T15:40:54+07:00" level=debug msg="requested aes key for file 28ee07ee20c6eca5442c30b1a000a6908db52892, gid: 1INKoD3iLAGucA61tkCEJt" Jun 10 15:40:55 volumio go-librespot[1590]: time="2025-06-10T15:40:55+07:00" level=debug msg="fetched first chunk of 21, total size is 10733840 bytes" uri="spotify:track:1INKoD3iLAGucA61tkCEJt" Jun 10 15:40:55 volumio go-librespot[1590]: time="2025-06-10T15:40:55+07:00" level=info msg="prefetched track \"These Bones\" (duration: 226293ms)" uri="spotify:track:1INKoD3iLAGucA61tkCEJt" Jun 10 15:40:55 volumio go-librespot[1590]: time="2025-06-10T15:40:55+07:00" level=debug msg="fetched chunk 1/20, size: 524288" uri="spotify:track:1INKoD3iLAGucA61tkCEJt" Jun 10 15:40:55 volumio go-librespot[1590]: time="2025-06-10T15:40:55+07:00" level=debug msg="fetched chunk 2/20, size: 524288" uri="spotify:track:1INKoD3iLAGucA61tkCEJt" Jun 10 15:40:55 volumio go-librespot[1590]: time="2025-06-10T15:40:55+07:00" level=debug msg="fetched chunk 3/20, size: 524288" uri="spotify:track:1INKoD3iLAGucA61tkCEJt" Jun 10 15:40:59 volumio volumio-remote-updater[828]: [2025-06-10 15:40:59] [connect] Successful connection Jun 10 15:41:14 volumio go-librespot[1590]: time="2025-06-10T15:41:14+07:00" level=trace msg="sent dealer ping" Jun 10 15:41:14 volumio go-librespot[1590]: time="2025-06-10T15:41:14+07:00" level=trace msg="received dealer pong" Jun 10 15:41:14 volumio volumio-remote-updater[828]: [2025-06-10 15:41:14] [connect] Successful connection Jun 10 15:41:22 volumio volumio[1202]: info: Checking if install.sh is present Jun 10 15:41:22 volumio volumio[1202]: info: CorePlayQueue::getTrack 6 Jun 10 15:41:22 volumio volumio[1202]: info: CorePlayQueue::getTrack 7 Jun 10 15:41:22 volumio volumio[1202]: info: Prefetching next song Jun 10 15:41:22 volumio volumio[1202]: info: [1749544882207] ControllerSpotify::prefetch Jun 10 15:41:22 volumio volumio[1202]: info: Sending Spotify command with payload to local API: /player/add_to_queue Jun 10 15:41:22 volumio volumio[1202]: info: Executing install.sh Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.4 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 10 15:41:22 volumio sudo[9389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Jun 10 15:41:22 volumio sudo[9389]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.4 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.4 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 15 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.4 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 16 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9:3000 from 192.168.1.4 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 17 Jun 10 15:41:22 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 15:41:22 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 15:41:22 volumio volumio[1202]: info: Discovery: Getting this device information Jun 10 15:41:22 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:22 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 15:41:22 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 15:41:22 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 15:41:22 volumio volumio[1202]: info: Discovery: Getting this device information Jun 10 15:41:22 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:22 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Jun 10 15:41:22 volumio go-librespot[1590]: time="2025-06-10T15:41:22+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 10 15:41:22 volumio go-librespot[1590]: time="2025-06-10T15:41:22+07:00" level=debug msg="prefetching next track" uri="spotify:track:1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:22 volumio go-librespot[1590]: time="2025-06-10T15:41:22+07:00" level=debug msg="selected format OGG_VORBIS_320 (a3f90bed9dae6650fe5504740811581b04017f70)" uri="spotify:track:1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:22 volumio go-librespot[1590]: time="2025-06-10T15:41:22+07:00" level=debug msg="requested aes key for file a3f90bed9dae6650fe5504740811581b04017f70, gid: 1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 26 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 27 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 28 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 29 Jun 10 15:41:22 volumio go-librespot[1590]: time="2025-06-10T15:41:22+07:00" level=debug msg="fetched first chunk of 34, total size is 17510040 bytes" uri="spotify:track:1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:22 volumio go-librespot[1590]: time="2025-06-10T15:41:22+07:00" level=info msg="prefetched track \"What A Wonderful World\" (duration: 454920ms)" uri="spotify:track:1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:22 volumio go-librespot[1590]: time="2025-06-10T15:41:22+07:00" level=debug msg="fetched chunk 2/33, size: 524288" uri="spotify:track:1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 30 Jun 10 15:41:22 volumio go-librespot[1590]: time="2025-06-10T15:41:22+07:00" level=debug msg="fetched chunk 3/33, size: 524288" uri="spotify:track:1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 32 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 33 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 34 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 35 Jun 10 15:41:22 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 36 Jun 10 15:41:23 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 37 Jun 10 15:41:23 volumio go-librespot[1590]: time="2025-06-10T15:41:23+07:00" level=debug msg="fetched chunk 1/33, size: 524288" uri="spotify:track:1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:23 volumio volumio[1202]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Jun 10 15:41:23 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:23 volumio volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 36 Jun 10 15:41:23 volumio volumio[1202]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 39 Jun 10 15:41:23 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:23 volumio volumio[1202]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 40 Jun 10 15:41:23 volumio volumio[1202]: info: CoreStateMachine::startPlaybackTimer Jun 10 15:41:23 volumio volumio[1202]: info: CorePlayQueue::getTrack 7 Jun 10 15:41:24 volumio volumio[1202]: info: CoreStateMachine::pushState Jun 10 15:41:24 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 15:41:24 volumio volumio[1202]: info: CoreCommandRouter::volumioPushState Jun 10 15:41:24 volumio volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 36 Jun 10 15:41:24 volumio go-librespot[1590]: time="2025-06-10T15:41:24+07:00" level=trace msg="emitting websocket event: not_playing" Jun 10 15:41:24 volumio go-librespot[1590]: time="2025-06-10T15:41:24+07:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:24 volumio volumio[1202]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","play_origin":"playlist"}} Jun 10 15:41:24 volumio volumio[1202]: error: Failed to decode event: not_playing Jun 10 15:41:24 volumio ntpd[996]: Soliciting pool server 2401:5b60:0:2::21 Jun 10 15:41:24 volumio go-librespot[1590]: time="2025-06-10T15:41:24+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 10 15:41:24 volumio go-librespot[1590]: time="2025-06-10T15:41:24+07:00" level=trace msg="emitting websocket event: will_play" Jun 10 15:41:24 volumio go-librespot[1590]: time="2025-06-10T15:41:24+07:00" level=info msg="loaded track \"What A Wonderful World\" (paused: false, position: 0ms, duration: 454920ms, prefetched: true)" uri="spotify:track:1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:24 volumio volumio[1202]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1sXmPBgVaFfQ0A4balBzut","play_origin":"playlist"}} Jun 10 15:41:24 volumio go-librespot[1590]: time="2025-06-10T15:41:24+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 10 15:41:24 volumio go-librespot[1590]: time="2025-06-10T15:41:24+07:00" level=trace msg="scheduling prefetch in 425s" Jun 10 15:41:24 volumio go-librespot[1590]: time="2025-06-10T15:41:24+07:00" level=trace msg="emitting websocket event: metadata" Jun 10 15:41:24 volumio volumio[1202]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1sXmPBgVaFfQ0A4balBzut","name":"What A Wonderful World","artist_names":["Chris Botti","Mark Knopfler"],"album_name":"Chris Botti: Impressions","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02120fb77e89c85fb7c82fe40b","position":0,"duration":454920,"release_date":"year:2012 month:1 day:1","track_number":13,"disc_number":1}} Jun 10 15:41:24 volumio go-librespot[1590]: time="2025-06-10T15:41:24+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 10 15:41:24 volumio go-librespot[1590]: time="2025-06-10T15:41:24+07:00" level=trace msg="emitting websocket event: playing" Jun 10 15:41:24 volumio volumio[1202]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1sXmPBgVaFfQ0A4balBzut","play_origin":"playlist"}} Jun 10 15:41:24 volumio volumio[1202]: SPOTIFY: PUSH STATE SPOTIFY Jun 10 15:41:24 volumio volumio[1202]: SPOTIFY: {"status":"play","service":"spop","title":"What A Wonderful World","artist":"Chris Botti, Mark Knopfler","album":"Chris Botti: Impressions","albumart":"https://i.scdn.co/image/ab67616d00001e02120fb77e89c85fb7c82fe40b","uri":"spotify:track:1sXmPBgVaFfQ0A4balBzut","trackType":"spotify","seek":0,"duration":454,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 10 15:41:24 volumio volumio[1202]: info: CoreCommandRouter::servicePushState Jun 10 15:41:24 volumio volumio[1202]: info: CoreStateMachine::pushState Jun 10 15:41:24 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 15:41:24 volumio volumio[1202]: info: CoreCommandRouter::volumioPushState Jun 10 15:41:24 volumio volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 36 Jun 10 15:41:25 volumio volumio[1202]: SPOTIFY: PUSH STATE SPOTIFY Jun 10 15:41:25 volumio volumio[1202]: SPOTIFY: {"status":"play","service":"spop","title":"What A Wonderful World","artist":"Chris Botti, Mark Knopfler","album":"Chris Botti: Impressions","albumart":"https://i.scdn.co/image/ab67616d00001e02120fb77e89c85fb7c82fe40b","uri":"spotify:track:1sXmPBgVaFfQ0A4balBzut","trackType":"spotify","seek":0,"duration":454,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":true,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 10 15:41:25 volumio volumio[1202]: info: CoreCommandRouter::servicePushState Jun 10 15:41:25 volumio volumio[1202]: info: CoreStateMachine::pushState Jun 10 15:41:25 volumio volumio[1202]: info: CoreCommandRouter::volumioPushState Jun 10 15:41:25 volumio volumio[1202]: SPOTIFY: RECEIVED VOLUMIO VOLUME 36 Jun 10 15:41:25 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 41 Jun 10 15:41:25 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 42 Jun 10 15:41:25 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 43 Jun 10 15:41:25 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 44 Jun 10 15:41:25 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 45 Jun 10 15:41:25 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 46 Jun 10 15:41:25 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 47 Jun 10 15:41:25 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 48 Jun 10 15:41:25 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 49 Jun 10 15:41:25 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 50 Jun 10 15:41:25 volumio volumio[1202]: info: copying demo flters Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/ Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/2XEQ15/ Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/PEQ/ Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/convfir/ Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/convfir/kef.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/convfir/test.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/EQ15/ Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/EQ15/.Bass-FusionDsp.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/EQ15/.Classic-FusionDsp.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/EQ15/.Voice-FusionDsp.json Jun 10 15:41:25 volumio volumio[1202]: info: ./presets/EQ15/.Rock-FusionDsp.json Jun 10 15:41:25 volumio sudo[9422]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Jun 10 15:41:25 volumio sudo[9422]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:25 volumio volumio[1202]: info: Installing/fusiondsp dependencies Jun 10 15:41:26 volumio volumio[1202]: error: error Jun 10 15:41:26 volumio volumio[1202]: info: Hit:1 https://deb.nodesource.com/node_14.x buster InRelease Jun 10 15:41:27 volumio volumio[1202]: info: Get:2 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] Jun 10 15:41:27 volumio volumio[1202]: info: Get:3 http://archive.raspberrypi.org/debian buster InRelease [54.2 kB] Jun 10 15:41:28 volumio volumio[1202]: verbose: New Socket.io Connection to 192.168.1.9 from 192.168.1.4 UA: Mozilla/5.0 (Linux; Android 14; 21081111RG Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 51 Jun 10 15:41:28 volumio volumio[1202]: info: Get:4 http://archive.raspberrypi.org/debian buster/main armhf Packages [400 kB] Jun 10 15:41:28 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 10 15:41:29 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 52 Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 10 15:41:29 volumio volumio[1202]: info: Received Get System Info Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 15:41:29 volumio volumio[1202]: info: Discovery: Getting this device information Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:29 volumio volumio[1202]: info: Listing playlists Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 10 15:41:29 volumio volumio[1202]: info: Received Get System Info Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 15:41:29 volumio volumio[1202]: info: Discovery: Getting this device information Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::volumioGetState Jun 10 15:41:29 volumio volumio[1202]: info: Listing playlists Jun 10 15:41:29 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 10 15:41:29 volumio volumio-remote-updater[828]: [2025-06-10 15:41:29] [connect] Successful connection Jun 10 15:41:29 volumio volumio[1202]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 53 Jun 10 15:41:29 volumio volumio-remote-updater[828]: [2025-06-10 15:41:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749544889 101 Jun 10 15:41:30 volumio volumio[1202]: info: Fetched 470 kB in 4s (132 kB/s) Jun 10 15:41:37 volumio volumio[1202]: info: Reading package lists... Jun 10 15:41:38 volumio volumio[1202]: info: Building dependency tree... Jun 10 15:41:38 volumio volumio[1202]: info: Reading state information... Jun 10 15:41:38 volumio volumio[1202]: info: All packages are up to date. Jun 10 15:41:38 volumio sudo[9422]: pam_unix(sudo:session): session closed for user root Jun 10 15:41:38 volumio sudo[9655]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Jun 10 15:41:38 volumio sudo[9655]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:38 volumio volumio[1202]: info: Reading package lists... Jun 10 15:41:38 volumio volumio[1202]: info: Building dependency tree... Jun 10 15:41:38 volumio volumio[1202]: info: Reading state information... Jun 10 15:41:38 volumio volumio[1202]: info: The following additional packages will be installed: Jun 10 15:41:38 volumio volumio[1202]: info: build-essential bzip2 dh-python dpkg-dev fakeroot g++ g++-8 gcc gcc-8 Jun 10 15:41:38 volumio volumio[1202]: info: libalgorithm-diff-perl libalgorithm-diff-xs-perl libalgorithm-merge-perl Jun 10 15:41:38 volumio volumio[1202]: info: libasan5 libcc1-0 libexpat1-dev libfakeroot libgcc-8-dev libpython3-dev Jun 10 15:41:38 volumio volumio[1202]: info: libpython3.7 libpython3.7-dev libstdc++-8-dev libubsan1 make patch Jun 10 15:41:38 volumio volumio[1202]: info: python-pip-whl python3-asn1crypto python3-async-timeout python3-attr Jun 10 15:41:38 volumio volumio[1202]: info: python3-cffi-backend python3-crypto python3-cryptography python3-dbus Jun 10 15:41:38 volumio volumio[1202]: info: python3-dev python3-entrypoints python3-gi python3-keyring Jun 10 15:41:38 volumio volumio[1202]: info: python3-keyrings.alt python3-multidict python3-secretstorage Jun 10 15:41:38 volumio volumio[1202]: info: python3-setuptools python3-wheel python3-xdg python3-yarl python3.7-dev Jun 10 15:41:38 volumio volumio[1202]: info: Suggested packages: Jun 10 15:41:38 volumio volumio[1202]: info: bzip2-doc debian-keyring gcc-8-doc libstdc++6-8-dbg gcc-multilib autoconf Jun 10 15:41:38 volumio volumio[1202]: info: automake libtool flex bison gdb gcc-doc gcc-8-locales libgcc1-dbg Jun 10 15:41:38 volumio volumio[1202]: info: libgomp1-dbg libitm1-dbg libatomic1-dbg libasan5-dbg liblsan0-dbg Jun 10 15:41:38 volumio volumio[1202]: info: libtsan0-dbg libubsan1-dbg libmpx2-dbg libquadmath0-dbg libstdc++-8-doc Jun 10 15:41:38 volumio volumio[1202]: info: make-doc ed diffutils-doc python-attr-doc python-crypto-doc Jun 10 15:41:38 volumio volumio[1202]: info: python-cryptography-doc python3-cryptography-vectors python-dbus-doc Jun 10 15:41:38 volumio volumio[1202]: info: python3-dbus-dbg gnome-keyring libkf5wallet-bin gir1.2-gnomekeyring-1.0 Jun 10 15:41:38 volumio volumio[1202]: info: python-secretstorage-doc python-setuptools-doc Jun 10 15:41:38 volumio volumio[1202]: info: Enabling plugin fusiondsp Jun 10 15:41:38 volumio volumio[1202]: info: Loading plugin "fusiondsp"... Jun 10 15:41:39 volumio volumio[1202]: info: Preparing to generate the ALSA configuration file Jun 10 15:41:39 volumio volumio[1202]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 10 15:41:39 volumio volumio[1202]: info: Reading ALSA contributions from plugins. Jun 10 15:41:39 volumio volumio[1202]: info: The following NEW packages will be installed: Jun 10 15:41:39 volumio volumio[1202]: info: build-essential bzip2 dh-python dpkg-dev fakeroot g++ g++-8 gcc gcc-8 Jun 10 15:41:39 volumio volumio[1202]: info: libalgorithm-diff-perl libalgorithm-diff-xs-perl libalgorithm-merge-perl Jun 10 15:41:39 volumio volumio[1202]: info: libasan5 libcc1-0 libexpat1-dev libfakeroot libgcc-8-dev libpython3-dev Jun 10 15:41:39 volumio volumio[1202]: info: libpython3.7 libpython3.7-dev libstdc++-8-dev libubsan1 make patch Jun 10 15:41:39 volumio volumio[1202]: info: python-pip-whl python3-aiohttp python3-asn1crypto python3-async-timeout Jun 10 15:41:39 volumio volumio[1202]: info: python3-attr python3-cffi-backend python3-crypto python3-cryptography Jun 10 15:41:39 volumio volumio[1202]: info: python3-dbus python3-dev python3-entrypoints python3-gi python3-keyring Jun 10 15:41:39 volumio volumio[1202]: info: python3-keyrings.alt python3-multidict python3-pip python3-secretstorage Jun 10 15:41:39 volumio volumio[1202]: info: python3-setuptools python3-wheel python3-xdg python3-yarl python3.7-dev Jun 10 15:41:39 volumio volumio[1202]: info: Asound.conf file unchanged, so no further update is needed Jun 10 15:41:39 volumio volumio[1202]: info: Output device has changed, restarting MPD Jun 10 15:41:39 volumio sudo[9663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 15:41:39 volumio sudo[9663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:39 volumio sudo[9663]: pam_unix(sudo:session): session closed for user root Jun 10 15:41:39 volumio volumio[1202]: info: Output device has changed, restarting Shairport Sync Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 15:41:39 volumio sudo[9667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 15:41:39 volumio sudo[9667]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:39 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 15:41:39 volumio systemd[1]: mpd.service: Succeeded. Jun 10 15:41:39 volumio volumio[1202]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 15:41:39 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 15:41:39 volumio volumio[1202]: info: PLUGIN START: fusiondsp Jun 10 15:41:39 volumio volumio[1202]: info: Loading i18n strings for locale en Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - mixtype--------------------- Software Jun 10 15:41:39 volumio volumio[1202]: info: Preparing to generate the ALSA configuration file Jun 10 15:41:39 volumio volumio[1202]: info: Done. Jun 10 15:41:39 volumio volumio[1202]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 10 15:41:39 volumio volumio[1202]: info: Reading ALSA contributions from plugins. Jun 10 15:41:39 volumio volumio[1202]: info: MPD Permissions set Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: Starting Shairport Sync Jun 10 15:41:39 volumio systemd[1]: Starting Music Player Daemon... Jun 10 15:41:39 volumio volumio[1202]: info: Asound.conf file unchanged, so no further update is needed Jun 10 15:41:39 volumio volumio[1202]: info: Output device has changed, restarting MPD Jun 10 15:41:39 volumio sudo[9678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 15:41:39 volumio sudo[9678]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 15:41:39 volumio volumio[1202]: info: Output device has changed, restarting Shairport Sync Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 15:41:39 volumio sudo[9672]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 15:41:39 volumio sudo[9672]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:39 volumio sudo[9683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 15:41:39 volumio sudo[9683]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:39 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 10 15:41:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 15:41:39 volumio sudo[9672]: pam_unix(sudo:session): session closed for user root Jun 10 15:41:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 15:41:39 volumio sudo[9681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 15:41:39 volumio sudo[9681]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:39 volumio sudo[9678]: pam_unix(sudo:session): session closed for user root Jun 10 15:41:39 volumio sudo[9681]: pam_unix(sudo:session): session closed for user root Jun 10 15:41:39 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 10 15:41:39 volumio volumio[1202]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 15:41:39 volumio systemd[1]: mpd.service: Succeeded. Jun 10 15:41:39 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 15:41:39 volumio systemd[1]: Starting Music Player Daemon... Jun 10 15:41:39 volumio volumio[1202]: info: MPD Permissions set Jun 10 15:41:39 volumio volumio[1202]: info: Shairport-Sync Started Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:39 volumio volumio[1202]: info: Starting Shairport Sync Jun 10 15:41:39 volumio sudo[9693]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 15:41:39 volumio sudo[9700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 15:41:39 volumio sudo[9700]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:39 volumio sudo[9693]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:39 volumio sudo[9693]: pam_unix(sudo:session): session closed for user root Jun 10 15:41:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 15:41:39 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 10 15:41:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 15:41:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 15:41:39 volumio sudo[9700]: pam_unix(sudo:session): session closed for user root Jun 10 15:41:39 volumio volumio[1202]: info: Shairport-Sync Started Jun 10 15:41:39 volumio go-librespot[1590]: time="2025-06-10T15:41:39+07:00" level=debug msg="fetched chunk 4/33, size: 524288" uri="spotify:track:1sXmPBgVaFfQ0A4balBzut" Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio mpd[9703]: Jun 10 15:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 15:41:39 volumio systemd[1]: Started Music Player Daemon. Jun 10 15:41:39 volumio sudo[9667]: pam_unix(sudo:session): session closed for user root Jun 10 15:41:39 volumio sudo[9683]: pam_unix(sudo:session): session closed for user root Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 15:41:39 volumio volumio[1202]: error: MPD error: The expression evaluated to a falsy value: Jun 10 15:41:39 volumio volumio[1202]: assert.ok(self.idling) Jun 10 15:41:39 volumio volumio[1202]: error: The expression evaluated to a falsy value: Jun 10 15:41:39 volumio volumio[1202]: assert.ok(self.idling) Jun 10 15:41:39 volumio volumio[1202]: error: updateQueue error: null Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:39 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:40 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:40 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:40 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:40 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:40 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:40 volumio volumio[1202]: info: FusionDsp - undefined Jun 10 15:41:43 volumio volumio[1202]: info: 0 upgraded, 46 newly installed, 0 to remove and 2 not upgraded. Jun 10 15:41:43 volumio volumio[1202]: info: Need to get 72.4 MB of archives. Jun 10 15:41:43 volumio volumio[1202]: info: After this operation, 160 MB of additional disk space will be used. Jun 10 15:41:43 volumio volumio[1202]: info: Get:1 http://archive.raspberrypi.org/debian buster/main armhf python-pip-whl all 18.1-5+rpt1 [1591 kB] Jun 10 15:41:43 volumio volumio[1202]: info: Get:2 http://rpi.vietidc.com/raspbian buster/main armhf bzip2 armhf 1.0.6-9.2~deb10u2 [46.3 kB] Jun 10 15:41:43 volumio volumio[1202]: info: camilladsp service started and running in background, instance 1 Jun 10 15:41:43 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 15:41:43 volumio volumio[1202]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 10 15:41:43 volumio volumio[1202]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 10 15:41:43 volumio volumio[1202]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jun 10 15:41:43 volumio volumio[1202]: info: FusionDsp loaded Jun 10 15:41:43 volumio volumio[1202]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 15:41:43 volumio sudo[9724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 10 15:41:43 volumio sudo[9724]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 15:41:43 volumio volumio[1202]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 10 15:41:43 volumio volumio[1202]: info: Adding Signal Path Element [object Object] Jun 10 15:41:43 volumio volumio[1202]: info: Adding fusiondspeq DSP Signal Path Element Jun 10 15:41:43 volumio volumio[1202]: info: FusionDsp - ---- installed callbackRead Jun 10 15:41:43 volumio volumio[1202]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 15:41:43 volumio volumio[1202]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jun 10 15:41:43 volumio volumio[1202]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jun 10 15:41:43 volumio volumio[1202]: at onErrorNT (internal/child_process.js:465:16) Jun 10 15:41:43 volumio volumio[1202]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 10 15:41:43 volumio volumio[1202]: at runNextTicks (internal/process/task_queues.js:62:3) Jun 10 15:41:43 volumio volumio[1202]: at listOnTimeout (internal/timers.js:523:9) Jun 10 15:41:43 volumio volumio[1202]: at processTimers (internal/timers.js:497:7) { Jun 10 15:41:43 volumio volumio[1202]: errno: -2, Jun 10 15:41:43 volumio volumio[1202]: code: 'ENOENT', Jun 10 15:41:43 volumio volumio[1202]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jun 10 15:41:43 volumio volumio[1202]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jun 10 15:41:43 volumio volumio[1202]: spawnargs: [ Jun 10 15:41:43 volumio volumio[1202]: '-p', Jun 10 15:41:43 volumio volumio[1202]: 9876, Jun 10 15:41:43 volumio volumio[1202]: '-o', Jun 10 15:41:43 volumio volumio[1202]: '/tmp/camilladsp.log', Jun 10 15:41:43 volumio volumio[1202]: '-l', Jun 10 15:41:43 volumio volumio[1202]: 'warn', Jun 10 15:41:43 volumio volumio[1202]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jun 10 15:41:43 volumio volumio[1202]: ] Jun 10 15:41:43 volumio volumio[1202]: } Jun 10 15:41:43 volumio volumio[1202]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 15:41:43 volumio sudo[9724]: pam_unix(sudo:session): session closed for user root Jun 10 15:41:43 volumio sudo[9735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 15:40 Jun 10 15:41:43 volumio sudo[9735]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"