-- Logs begin at Thu 2019-02-14 10:12:00 GMT, end at Sun 2025-06-08 15:55:55 IST. -- Jun 08 15:54:00 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:00+01:00" level=trace msg="received accesspoint ping" Jun 08 15:54:00 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:00+01:00" level=trace msg="received accesspoint pong ack" Jun 08 15:54:00 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:00+01:00" level=trace msg="sent dealer ping" Jun 08 15:54:00 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:00+01:00" level=trace msg="received dealer pong" Jun 08 15:54:05 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:05 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:05 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:05 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:05 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:05 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:07 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:07 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:07 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:07 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:07 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:07 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:10 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:10 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:10 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:10 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:10 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:10 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:11 vol-liv-room volumio[870]: verbose: New Socket.io Connection to 192.168.0.57 from 192.168.0.245 UA: Mozilla/5.0 (Linux; Android 15; Pixel 6 Build/BP1A.250505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:11 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 08 15:54:11 vol-liv-room volumio[870]: info: Received Get System Info Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:11 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:11 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:11 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:11 vol-liv-room volumio[870]: info: Listing playlists Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetQueue Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreStateMachine::getQueue Jun 08 15:54:11 vol-liv-room volumio[870]: info: CorePlayQueue::getQueue Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:11 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:11 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:11 vol-liv-room volumio[870]: verbose: New Socket.io Connection to 192.168.0.57:3000 from 192.168.0.245 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jun 08 15:54:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:18 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:18 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:18 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:26 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:26 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:26 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:26 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:26 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:26 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:30 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:30+01:00" level=trace msg="sent dealer ping" Jun 08 15:54:30 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:30+01:00" level=trace msg="received dealer pong" Jun 08 15:54:32 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:32 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:32 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:32 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:32 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:32 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:34 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:34 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:34 vol-liv-room volumio[870]: info: Prefetching next song Jun 08 15:54:34 vol-liv-room volumio[870]: info: [1749394474559] ControllerSpotify::prefetch Jun 08 15:54:34 vol-liv-room volumio[870]: info: Sending Spotify command with payload to local API: /player/add_to_queue Jun 08 15:54:34 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:34+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:54:34 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:34+01:00" level=debug msg="prefetching next track" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:54:34 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:34+01:00" level=debug msg="selected format OGG_VORBIS_320 (b18089ebc52daafca6b0f5c18c1071577e5abdb2)" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:54:34 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:34+01:00" level=debug msg="requested aes key for file b18089ebc52daafca6b0f5c18c1071577e5abdb2, gid: 28AvvchUP85ihRe9xCzcfB" Jun 08 15:54:35 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:35+01:00" level=debug msg="fetched first chunk of 15, total size is 7522636 bytes" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:54:35 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:35+01:00" level=info msg="prefetched track \"Da Joint\" (duration: 208360ms)" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:54:35 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:35+01:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:54:36 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:36+01:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:54:36 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:36+01:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:54:37 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:37 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:37 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:37 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:37 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:37 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:38 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:38 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:38 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:38 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:38 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:38 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:38 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:38 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:38 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:38 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:38 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 14 Jun 08 15:54:38 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:38 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:38+01:00" level=trace msg="emitting websocket event: not_playing" Jun 08 15:54:38 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:38+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:54:38 vol-liv-room volumio[870]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:6I15UB0IPWQhJNJEPQ1gLE","play_origin":"go-librespot"}} Jun 08 15:54:38 vol-liv-room volumio[870]: error: Failed to decode event: not_playing Jun 08 15:54:38 vol-liv-room volumio[870]: info: CoreStateMachine::startPlaybackTimer Jun 08 15:54:38 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:38 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:38+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:54:38 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:38+01:00" level=trace msg="emitting websocket event: will_play" Jun 08 15:54:38 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:38+01:00" level=info msg="loaded track \"Da Joint\" (paused: false, position: 0ms, duration: 208360ms, prefetched: true)" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:28AvvchUP85ihRe9xCzcfB","play_origin":"go-librespot"}} Jun 08 15:54:39 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:39+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:54:39 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:39+01:00" level=trace msg="scheduling prefetch in 178s" Jun 08 15:54:39 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:39+01:00" level=trace msg="emitting websocket event: metadata" Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:28AvvchUP85ihRe9xCzcfB","name":"Da Joint","artist_names":["EPMD"],"album_name":"Back In Business","album_cover_url":"https://i.scdn.co/image/ab67616d00001e020b32162c6f11792784b93ca6","position":0,"duration":208360,"release_date":"year:1997 month:9 day:16","track_number":3,"disc_number":1}} Jun 08 15:54:39 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:39+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:54:39 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:39+01:00" level=trace msg="emitting websocket event: playing" Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:28AvvchUP85ihRe9xCzcfB","play_origin":"go-librespot"}} Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: PUSH STATE SPOTIFY Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: {"status":"play","service":"spop","title":"Da Joint","artist":"EPMD","album":"Back In Business","albumart":"https://i.scdn.co/image/ab67616d00001e020b32162c6f11792784b93ca6","uri":"spotify:track:28AvvchUP85ihRe9xCzcfB","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreCommandRouter::servicePushState Jun 08 15:54:39 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:39 vol-liv-room volumio[870]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Da Joint","artist":"EPMD","album":"Back In Business","albumart":"https://i.scdn.co/image/ab67616d00001e020b32162c6f11792784b93ca6","uri":"spotify:track:28AvvchUP85ihRe9xCzcfB","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:54:39 vol-liv-room volumio[870]: verbose: CURRENT POSITION 11 Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreStateMachine::syncState stateService play Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreStateMachine::syncState currentStatus play Jun 08 15:54:39 vol-liv-room volumio[870]: info: Received an update from plugin. extracting info from payload Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreStateMachine::pushState Jun 08 15:54:39 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioPushState Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreStateMachine::pushState Jun 08 15:54:39 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioPushState Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreStateMachine::pushState Jun 08 15:54:39 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioPushState Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: PUSH STATE SPOTIFY Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: {"status":"play","service":"spop","title":"Da Joint","artist":"EPMD","album":"Back In Business","albumart":"https://i.scdn.co/image/ab67616d00001e020b32162c6f11792784b93ca6","uri":"spotify:track:28AvvchUP85ihRe9xCzcfB","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreCommandRouter::servicePushState Jun 08 15:54:39 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:39 vol-liv-room volumio[870]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Da Joint","artist":"EPMD","album":"Back In Business","albumart":"https://i.scdn.co/image/ab67616d00001e020b32162c6f11792784b93ca6","uri":"spotify:track:28AvvchUP85ihRe9xCzcfB","trackType":"spotify","seek":0,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:54:39 vol-liv-room volumio[870]: verbose: CURRENT POSITION 11 Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreStateMachine::syncState stateService play Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreStateMachine::syncState currentStatus play Jun 08 15:54:39 vol-liv-room volumio[870]: info: Received an update from plugin. extracting info from payload Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreStateMachine::pushState Jun 08 15:54:39 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioPushState Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreStateMachine::pushState Jun 08 15:54:39 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:39 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioPushState Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 08 15:54:39 vol-liv-room volumio[870]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jun 08 15:54:47 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:47 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:47 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:47 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:47 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:47 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:54 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:54:54 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:54:54 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:54:54 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:54:54 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:54:54 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:54:54 vol-liv-room go-librespot[1081]: time="2025-06-08T15:54:54+01:00" level=debug msg="fetched chunk 4/14, size: 524288" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:55:00 vol-liv-room go-librespot[1081]: time="2025-06-08T15:55:00+01:00" level=trace msg="sent dealer ping" Jun 08 15:55:00 vol-liv-room go-librespot[1081]: time="2025-06-08T15:55:00+01:00" level=trace msg="received dealer pong" Jun 08 15:55:08 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:08 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:08 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:08 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:08 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:08 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:09 vol-liv-room go-librespot[1081]: time="2025-06-08T15:55:09+01:00" level=debug msg="fetched chunk 5/14, size: 524288" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:55:09 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:09 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:09 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:09 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:09 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:09 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:09 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:09 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:10 vol-liv-room sudo[3377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 08 15:55:10 vol-liv-room sudo[3377]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:10 vol-liv-room sudo[3377]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:10 vol-liv-room sudo[3379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 08 15:55:10 vol-liv-room sudo[3379]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:10 vol-liv-room sudo[3379]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:10 vol-liv-room volumio[870]: verbose: New Socket.io Connection to 192.168.0.57 from 192.168.0.245 UA: Mozilla/5.0 (Linux; Android 15; Pixel 6 Build/BP1A.250505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 08 15:55:10 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 15:55:10 vol-liv-room sudo[3383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 08 15:55:10 vol-liv-room sudo[3383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:10 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 08 15:55:10 vol-liv-room sudo[3383]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:10 vol-liv-room sudo[3385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 08 15:55:10 vol-liv-room sudo[3385]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:10 vol-liv-room sudo[3385]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:10 vol-liv-room volumio[870]: verbose: New Socket.io Connection to 192.168.0.57 from 192.168.0.245 UA: Mozilla/5.0 (Linux; Android 15; Pixel 6 Build/BP1A.250505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:11 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 08 15:55:11 vol-liv-room volumio[870]: info: Received Get System Info Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:11 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:11 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:11 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:11 vol-liv-room volumio[870]: info: Listing playlists Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 08 15:55:11 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 08 15:55:12 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 15:55:12 vol-liv-room volumio[870]: info: Received Get System Info Jun 08 15:55:12 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:12 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:12 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:12 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:12 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:12 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:12 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 08 15:55:13 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 15:55:13 vol-liv-room volumio[870]: info: Received Get System Info Jun 08 15:55:13 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:13 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:13 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:13 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:13 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:13 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:13 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:13 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:13 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:13 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:13 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:13 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 08 15:55:18 vol-liv-room volumio[870]: info: Getting Alsa Cards List without I2S DAC Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 08 15:55:18 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 08 15:55:20 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 08 15:55:24 vol-liv-room go-librespot[1081]: time="2025-06-08T15:55:24+01:00" level=debug msg="fetched chunk 6/14, size: 524288" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:55:27 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:27 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:27 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:27 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:27 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:27 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:28 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 08 15:55:28 vol-liv-room volumio[870]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 08 15:55:28 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 08 15:55:28 vol-liv-room volumio[870]: info: Received Get System Version Jun 08 15:55:28 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 08 15:55:28 vol-liv-room volumio[870]: info: Received Get System Info Jun 08 15:55:28 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:28 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:28 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:28 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:28 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:28 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:30 vol-liv-room go-librespot[1081]: time="2025-06-08T15:55:30+01:00" level=trace msg="sent dealer ping" Jun 08 15:55:30 vol-liv-room go-librespot[1081]: time="2025-06-08T15:55:30+01:00" level=trace msg="received dealer pong" Jun 08 15:55:34 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:34 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:34 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:34 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:34 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:34 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:38 vol-liv-room go-librespot[1081]: time="2025-06-08T15:55:38+01:00" level=debug msg="fetched chunk 7/14, size: 524288" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:55:39 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:39 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:39 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:39 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:39 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:39 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:41 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 15:55:41 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 08 15:55:48 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:55:48 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:55:48 vol-liv-room volumio[870]: info: Discovery: Getting this device information Jun 08 15:55:48 vol-liv-room volumio[870]: info: CoreCommandRouter::volumioGetState Jun 08 15:55:48 vol-liv-room volumio[870]: info: CorePlayQueue::getTrack 11 Jun 08 15:55:48 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:50 vol-liv-room volumio[870]: info: Enabling plugin fusiondsp Jun 08 15:55:50 vol-liv-room volumio[870]: info: Loading plugin "fusiondsp"... Jun 08 15:55:50 vol-liv-room volumio[870]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Jun 08 15:55:50 vol-liv-room volumio[870]: info: Preparing to generate the ALSA configuration file Jun 08 15:55:50 vol-liv-room volumio[870]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 08 15:55:50 vol-liv-room volumio[870]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 08 15:55:50 vol-liv-room volumio[870]: info: Reading ALSA contributions from plugins. Jun 08 15:55:50 vol-liv-room volumio[870]: info: Asound.conf file written Jun 08 15:55:50 vol-liv-room sudo[3500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 08 15:55:50 vol-liv-room sudo[3500]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:50 vol-liv-room sudo[3500]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:51 vol-liv-room volumio[870]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Jun 08 15:55:51 vol-liv-room volumio[870]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Jun 08 15:55:51 vol-liv-room volumio[870]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Jun 08 15:55:51 vol-liv-room volumio[870]: info: Output device has changed, restarting MPD Jun 08 15:55:51 vol-liv-room sudo[3505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 08 15:55:51 vol-liv-room volumio[870]: info: Output device has changed, restarting Shairport Sync Jun 08 15:55:51 vol-liv-room sudo[3505]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 15:55:51 vol-liv-room sudo[3505]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:51 vol-liv-room sudo[3507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 08 15:55:51 vol-liv-room sudo[3507]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:51 vol-liv-room systemd[1]: Stopping Music Player Daemon... Jun 08 15:55:51 vol-liv-room volumio[870]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 08 15:55:51 vol-liv-room systemd[1]: mpd.service: Succeeded. Jun 08 15:55:51 vol-liv-room volumio[870]: info: PLUGIN START: fusiondsp Jun 08 15:55:51 vol-liv-room systemd[1]: Stopped Music Player Daemon. Jun 08 15:55:51 vol-liv-room volumio[870]: info: Loading i18n strings for locale en Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: FusionDsp - mixtype--------------------- Hardware Jun 08 15:55:51 vol-liv-room volumio[870]: info: Preparing to generate the ALSA configuration file Jun 08 15:55:51 vol-liv-room systemd[1]: Starting Music Player Daemon... Jun 08 15:55:51 vol-liv-room volumio[870]: info: Done. Jun 08 15:55:51 vol-liv-room volumio[870]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 08 15:55:51 vol-liv-room volumio[870]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 08 15:55:51 vol-liv-room volumio[870]: info: Reading ALSA contributions from plugins. Jun 08 15:55:51 vol-liv-room volumio[870]: info: MPD Permissions set Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:51 vol-liv-room volumio[870]: info: FusionDsp - Jun 08 15:55:51 vol-liv-room sudo[3513]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 08 15:55:51 vol-liv-room sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:51 vol-liv-room sudo[3513]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:51 vol-liv-room volumio[870]: info: FusionDsp - Error: TypeError: Cannot read property 'split' of undefined Jun 08 15:55:51 vol-liv-room volumio[870]: info: Starting Shairport Sync Jun 08 15:55:52 vol-liv-room volumio[870]: info: FusionDsp - undefined Jun 08 15:55:52 vol-liv-room volumio[870]: info: Asound.conf file unchanged, so no further update is needed Jun 08 15:55:52 vol-liv-room volumio[870]: info: Output device has changed, restarting MPD Jun 08 15:55:52 vol-liv-room sudo[3521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 08 15:55:52 vol-liv-room sudo[3521]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:52 vol-liv-room volumio[870]: info: Output device has changed, restarting Shairport Sync Jun 08 15:55:52 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:52 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 15:55:52 vol-liv-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 08 15:55:52 vol-liv-room systemd[1]: shairport-sync.service: Succeeded. Jun 08 15:55:52 vol-liv-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 08 15:55:52 vol-liv-room sudo[3524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 08 15:55:52 vol-liv-room sudo[3524]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:52 vol-liv-room sudo[3524]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:52 vol-liv-room sudo[3530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 08 15:55:52 vol-liv-room sudo[3530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:52 vol-liv-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 08 15:55:52 vol-liv-room sudo[3521]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:52 vol-liv-room volumio[870]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 08 15:55:52 vol-liv-room volumio[870]: info: MPD Permissions set Jun 08 15:55:52 vol-liv-room volumio[870]: info: Shairport-Sync Started Jun 08 15:55:52 vol-liv-room systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 08 15:55:52 vol-liv-room systemd[1]: mpd.service: Succeeded. Jun 08 15:55:52 vol-liv-room systemd[1]: Stopped Music Player Daemon. Jun 08 15:55:52 vol-liv-room volumio[870]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' Jun 08 15:55:52 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:52 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:52 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:52 vol-liv-room systemd[1]: Starting Music Player Daemon... Jun 08 15:55:52 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 08 15:55:52 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:52 vol-liv-room volumio[870]: info: Starting Shairport Sync Jun 08 15:55:52 vol-liv-room sudo[3557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 08 15:55:52 vol-liv-room sudo[3557]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:52 vol-liv-room systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 08 15:55:52 vol-liv-room systemd[1]: shairport-sync.service: Succeeded. Jun 08 15:55:52 vol-liv-room systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 08 15:55:52 vol-liv-room sudo[3550]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 08 15:55:52 vol-liv-room sudo[3550]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:52 vol-liv-room sudo[3550]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:52 vol-liv-room systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 08 15:55:52 vol-liv-room sudo[3557]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:52 vol-liv-room volumio[870]: info: Shairport-Sync Started Jun 08 15:55:53 vol-liv-room volumio[870]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 15:55:53 vol-liv-room volumio[870]: info: FusionDsp - Jun 08 15:55:53 vol-liv-room go-librespot[1081]: time="2025-06-08T15:55:53+01:00" level=debug msg="fetched chunk 8/14, size: 524288" uri="spotify:track:28AvvchUP85ihRe9xCzcfB" Jun 08 15:55:53 vol-liv-room volumio[870]: info: FusionDsp - Error: TypeError: Cannot read property 'split' of undefined Jun 08 15:55:54 vol-liv-room volumio[870]: info: camilladsp service started and running in background, instance 1 Jun 08 15:55:54 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 08 15:55:54 vol-liv-room volumio[870]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Jun 08 15:55:54 vol-liv-room volumio[870]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 08 15:55:54 vol-liv-room volumio[870]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Jun 08 15:55:54 vol-liv-room volumio[870]: info: FusionDsp loaded Jun 08 15:55:54 vol-liv-room volumio[870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:55:54 vol-liv-room sudo[3574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 08 15:55:54 vol-liv-room sudo[3574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:55:54 vol-liv-room volumio[870]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 08 15:55:54 vol-liv-room volumio[870]: info: Adding Signal Path Element [object Object] Jun 08 15:55:54 vol-liv-room volumio[870]: info: Adding fusiondspeq DSP Signal Path Element Jun 08 15:55:54 vol-liv-room volumio[870]: info: FusionDsp - ---- installed callbackRead Jun 08 15:55:54 vol-liv-room volumio[870]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 15:55:54 vol-liv-room volumio[870]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Jun 08 15:55:54 vol-liv-room volumio[870]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jun 08 15:55:54 vol-liv-room volumio[870]: at onErrorNT (internal/child_process.js:465:16) Jun 08 15:55:54 vol-liv-room volumio[870]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 08 15:55:54 vol-liv-room volumio[870]: at runNextTicks (internal/process/task_queues.js:62:3) Jun 08 15:55:54 vol-liv-room volumio[870]: at listOnTimeout (internal/timers.js:523:9) Jun 08 15:55:54 vol-liv-room volumio[870]: at processTimers (internal/timers.js:497:7) { Jun 08 15:55:54 vol-liv-room volumio[870]: errno: -13, Jun 08 15:55:54 vol-liv-room volumio[870]: code: 'EACCES', Jun 08 15:55:54 vol-liv-room volumio[870]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jun 08 15:55:54 vol-liv-room volumio[870]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jun 08 15:55:54 vol-liv-room volumio[870]: spawnargs: [ Jun 08 15:55:54 vol-liv-room volumio[870]: '-p', Jun 08 15:55:54 vol-liv-room volumio[870]: 9876, Jun 08 15:55:54 vol-liv-room volumio[870]: '-o', Jun 08 15:55:54 vol-liv-room volumio[870]: '/tmp/camilladsp.log', Jun 08 15:55:54 vol-liv-room volumio[870]: '-l', Jun 08 15:55:54 vol-liv-room volumio[870]: 'warn', Jun 08 15:55:54 vol-liv-room volumio[870]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jun 08 15:55:54 vol-liv-room volumio[870]: ] Jun 08 15:55:54 vol-liv-room volumio[870]: } Jun 08 15:55:54 vol-liv-room systemd[1]: Starting Cleanup of Temporary Directories... Jun 08 15:55:54 vol-liv-room volumio[870]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 15:55:54 vol-liv-room systemd[1]: Started FusionDsp Daemon. Jun 08 15:55:54 vol-liv-room sudo[3574]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:54 vol-liv-room systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jun 08 15:55:54 vol-liv-room systemd[1]: Started Cleanup of Temporary Directories. Jun 08 15:55:54 vol-liv-room volumio[3579]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jun 08 15:55:54 vol-liv-room systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jun 08 15:55:54 vol-liv-room systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jun 08 15:55:55 vol-liv-room mpd[3561]: Jun 08 15:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 08 15:55:55 vol-liv-room systemd[1]: Started Music Player Daemon. Jun 08 15:55:55 vol-liv-room sudo[3530]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:55 vol-liv-room sudo[3507]: pam_unix(sudo:session): session closed for user root Jun 08 15:55:55 vol-liv-room sudo[3589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 15:54 Jun 08 15:55:55 vol-liv-room sudo[3589]: 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"