-- Logs begin at Thu 2019-02-14 18:11:58 HKT, end at Sat 2025-05-24 22:57:07 HKT. -- May 24 22:56:01 volumio go-librespot[4532]: time="2025-05-24T22:56:01+08:00" level=trace msg="sent dealer ping" May 24 22:56:01 volumio go-librespot[4532]: time="2025-05-24T22:56:01+08:00" level=trace msg="received dealer pong" May 24 22:56:12 volumio volumio[1042]: info: CorePlayQueue::getTrack 8 May 24 22:56:12 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:12 volumio volumio[1042]: info: Prefetching next song May 24 22:56:12 volumio volumio[1042]: info: [1748098572046] ControllerTidal::prefetch May 24 22:56:12 volumio volumio[1042]: info: Getting stream with soundQuality HI_RES May 24 22:56:12 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/40859655&soundQuality=HI_RES" May 24 22:56:12 volumio volumio[1042]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/40859655&soundQuality=HI_RES" took 0 milliseconds May 24 22:56:12 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 22:56:12 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand consume 1 May 24 22:56:12 volumio volumio[1042]: info: May 24 22:56:12 volumio volumio[1042]: ---------------------------- MPD announces system playlist update May 24 22:56:12 volumio volumio[1042]: info: Ignoring MPD Status Update May 24 22:56:12 volumio volumio[1042]: info: sendMpdCommand consume 1 took 22 milliseconds May 24 22:56:12 volumio volumio[1042]: info: May 24 22:56:12 volumio volumio[1042]: ---------------------------- MPD announces system playlist update May 24 22:56:12 volumio volumio[1042]: info: Ignoring MPD Status Update May 24 22:56:12 volumio volumio[1042]: info: May 24 22:56:12 volumio volumio[1042]: ---------------------------- MPD announces system playlist update May 24 22:56:12 volumio volumio[1042]: info: Ignoring MPD Status Update May 24 22:56:12 volumio volumio[1042]: info: ------------------------------ 5ms May 24 22:56:12 volumio volumio[1042]: info: ------------------------------ 4ms May 24 22:56:12 volumio volumio[1042]: info: ------------------------------ 3ms May 24 22:56:16 volumio volumio[1042]: info: CoreStateMachine::startPlaybackTimer May 24 22:56:16 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:17 volumio volumio[1042]: info: CoreStateMachine::pushState May 24 22:56:17 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:17 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:56:17 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState May 24 22:56:17 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device May 24 22:56:17 volumio volumio[1042]: info: MRS: Pushing multiroomSync output May 24 22:56:17 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:17 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:17 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 24 22:56:22 volumio volumio[1042]: STREAMING PROXY: Handling url /?data=tidal://song/40859655&soundQuality=HI_RES May 24 22:56:22 volumio volumio[1042]: info: Executing endpoint getStreamUrltidal May 24 22:56:22 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 24 22:56:22 volumio volumio[1042]: info: getStreamUrl took 332 milliseconds May 24 22:56:22 volumio volumio[1042]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKAgDEiQ3MTBjNGM2MDllNTZiNGVmYTUyZDc1MWNhYTU3YTAyYy5tcDQ/0.flac?Expires=1748102182&Signature=J776ultSOjUWyD1JEdXRE0ot2Ge3I9bPs~PSYRzgWyON7hk~N89bENSPCE4Zq6elY3chmPCRvig8uM7TlbASo5jiLMw4JJf4pfEC5nLbwi1~Im-yIDCAzrT~P08MmSg23R0HfckLxxuiHRRhn050LgmBAlRc0RcKIKeuu8pPEGxI4RQQLmHNsN0HlmdKHBhIq-xzO2qsQH6hodazN6ZFY2E1ei1r0tE6oXV9O1JGEUdvhCniVJl3cjzUn68dCHqQNdKpQYpkfpRA-gLcSdwrZEtqhnelQe5hfpYCby-4qAgU-kiIIRJxn-KkXNwXWUVdn91T32Ejefw1N~HSYeYV3A__&Key-Pair-Id=K14LZCZ9QUI4JL May 24 22:56:22 volumio volumio[1042]: STREAMING PROXY: Response: 200, length: 33214492 May 24 22:56:25 volumio volumio[1042]: STREAMING PROXY: Client dropped request, destroying May 24 22:56:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 22:56:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 22:56:26 volumio volumio[1042]: info: Discovery: Getting this device information May 24 22:56:26 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:26 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:26 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 22:56:31 volumio go-librespot[4532]: time="2025-05-24T22:56:31+08:00" level=trace msg="received accesspoint ping" May 24 22:56:31 volumio go-librespot[4532]: time="2025-05-24T22:56:31+08:00" level=trace msg="received accesspoint pong ack" May 24 22:56:31 volumio go-librespot[4532]: time="2025-05-24T22:56:31+08:00" level=trace msg="sent dealer ping" May 24 22:56:31 volumio go-librespot[4532]: time="2025-05-24T22:56:31+08:00" level=trace msg="received dealer pong" May 24 22:56:31 volumio volumio[1042]: info: May 24 22:56:31 volumio volumio[1042]: ---------------------------- MPD announces system playlist update May 24 22:56:31 volumio volumio[1042]: info: Ignoring MPD Status Update May 24 22:56:31 volumio volumio[1042]: info: May 24 22:56:31 volumio volumio[1042]: ---------------------------- MPD announces state update: player May 24 22:56:31 volumio volumio[1042]: info: ControllerMpd::getState May 24 22:56:31 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status May 24 22:56:31 volumio volumio[1042]: info: May 24 22:56:31 volumio volumio[1042]: ---------------------------- MPD announces system playlist update May 24 22:56:31 volumio volumio[1042]: info: Ignoring MPD Status Update May 24 22:56:31 volumio volumio[1042]: info: May 24 22:56:31 volumio volumio[1042]: ---------------------------- MPD announces state update: player May 24 22:56:31 volumio volumio[1042]: info: ControllerMpd::getState May 24 22:56:31 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status May 24 22:56:31 volumio volumio[1042]: info: May 24 22:56:31 volumio volumio[1042]: ---------------------------- MPD announces system playlist update May 24 22:56:31 volumio volumio[1042]: info: Ignoring MPD Status Update May 24 22:56:31 volumio volumio[1042]: info: May 24 22:56:31 volumio volumio[1042]: ---------------------------- MPD announces state update: player May 24 22:56:31 volumio volumio[1042]: info: ControllerMpd::getState May 24 22:56:31 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand status May 24 22:56:31 volumio volumio[1042]: info: ------------------------------ 6ms May 24 22:56:31 volumio volumio[1042]: info: sendMpdCommand status took 4 milliseconds May 24 22:56:31 volumio volumio[1042]: info: ------------------------------ 3ms May 24 22:56:31 volumio volumio[1042]: info: sendMpdCommand status took 4 milliseconds May 24 22:56:31 volumio volumio[1042]: info: ------------------------------ 3ms May 24 22:56:31 volumio volumio[1042]: info: sendMpdCommand status took 2 milliseconds May 24 22:56:31 volumio volumio[1042]: verbose: ControllerMpd::parseState May 24 22:56:31 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 22:56:31 volumio volumio[1042]: verbose: ControllerMpd::parseState May 24 22:56:31 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 22:56:31 volumio volumio[1042]: verbose: ControllerMpd::parseState May 24 22:56:31 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 22:56:32 volumio volumio[1042]: info: sendMpdCommand playlistinfo took 2 milliseconds May 24 22:56:32 volumio volumio[1042]: info: sendMpdCommand playlistinfo took 2 milliseconds May 24 22:56:32 volumio volumio[1042]: verbose: ControllerMpd::parseTrackInfo May 24 22:56:32 volumio volumio[1042]: info: ControllerMpd::pushState May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::servicePushState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"958 Kbps","isStreaming":false,"title":"40859655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/40859655&soundQuality=HI_RES","trackType":"tidal"} May 24 22:56:32 volumio volumio[1042]: verbose: CURRENT POSITION 9 May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play May 24 22:56:32 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::pushState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::pushState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: ControllerMpd::pushState May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::servicePushState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"958 Kbps","isStreaming":false,"title":"40859655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/40859655&soundQuality=HI_RES","trackType":"tidal"} May 24 22:56:32 volumio volumio[1042]: verbose: CURRENT POSITION 9 May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play May 24 22:56:32 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::pushState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::pushState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: ------------------------------ 38ms May 24 22:56:32 volumio volumio[1042]: info: ------------------------------ 36ms May 24 22:56:32 volumio volumio[1042]: info: sendMpdCommand playlistinfo took 32 milliseconds May 24 22:56:32 volumio volumio[1042]: verbose: ControllerMpd::parseTrackInfo May 24 22:56:32 volumio volumio[1042]: info: ControllerMpd::pushState May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::servicePushState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"958 Kbps","isStreaming":false,"title":"40859655&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/40859655&soundQuality=HI_RES","trackType":"tidal"} May 24 22:56:32 volumio volumio[1042]: verbose: CURRENT POSITION 9 May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::syncState stateService play May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::syncState currentStatus play May 24 22:56:32 volumio volumio[1042]: info: Received an update from plugin. extracting info from payload May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::pushState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: CoreStateMachine::pushState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device May 24 22:56:32 volumio volumio[1042]: info: MRS: Pushing multiroomSync output May 24 22:56:32 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:32 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:32 volumio volumio[1042]: info: ------------------------------ 47ms May 24 22:56:32 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 24 22:56:32 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 24 22:56:32 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 24 22:56:32 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 24 22:56:32 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 24 22:56:32 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 24 22:56:37 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:37 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:38 volumio sudo[17014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 22:56:38 volumio sudo[17014]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:38 volumio sudo[17014]: pam_unix(sudo:session): session closed for user root May 24 22:56:38 volumio sudo[17017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:56:38 volumio sudo[17017]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:38 volumio sudo[17017]: pam_unix(sudo:session): session closed for user root May 24 22:56:38 volumio volumio[1042]: verbose: New Socket.io Connection to 192.168.68.52 from 192.168.68.51 UA: Mozilla/5.0 (Linux; Android 15; 2407FPN8EG Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 24 22:56:38 volumio sudo[17083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 22:56:38 volumio sudo[17083]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:38 volumio sudo[17083]: pam_unix(sudo:session): session closed for user root May 24 22:56:38 volumio sudo[17086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:56:38 volumio sudo[17086]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:38 volumio sudo[17086]: pam_unix(sudo:session): session closed for user root May 24 22:56:39 volumio volumio[1042]: verbose: New Socket.io Connection to 192.168.68.52 from 192.168.68.51 UA: Mozilla/5.0 (Linux; Android 15; 2407FPN8EG Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::volumioGetVisibleSources May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:39 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 22:56:39 volumio volumio[1042]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 24 22:56:39 volumio volumio[1042]: info: Received Get System Info May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 22:56:39 volumio volumio[1042]: info: Discovery: Getting this device information May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:39 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:39 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:39 volumio volumio[1042]: info: Listing playlists May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 22:56:39 volumio volumio[1042]: info: FusionDsp - Choose a preset May 24 22:56:39 volumio volumio[1042]: info: FusionDsp - May 24 22:56:39 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 24 22:56:40 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 24 22:56:40 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 22:56:40 volumio volumio[1042]: info: Received Get System Info May 24 22:56:40 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 22:56:40 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 22:56:40 volumio volumio[1042]: info: Discovery: Getting this device information May 24 22:56:40 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:40 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:40 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 22:56:41 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 22:56:41 volumio volumio[1042]: info: Received Get System Info May 24 22:56:41 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 22:56:41 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 22:56:41 volumio volumio[1042]: info: Discovery: Getting this device information May 24 22:56:41 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:41 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:41 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 22:56:43 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 22:56:43 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 24 22:56:43 volumio volumio[1042]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 22:56:43 volumio volumio[1042]: info: FusionDsp - Choose a preset May 24 22:56:43 volumio volumio[1042]: info: FusionDsp - May 24 22:56:45 volumio volumio[1042]: info: FusionDsp - Stopping FusionDsp service May 24 22:56:45 volumio volumio[1042]: info: camilladsp stopping service pid 11508... May 24 22:56:45 volumio volumio[1042]: info: camilladsp service terminated, instance 2 May 24 22:56:45 volumio volumio[1042]: info: Disabling plugin fusiondsp May 24 22:56:45 volumio volumio[1042]: info: Preparing to generate the ALSA configuration file May 24 22:56:45 volumio sudo[18124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 24 22:56:45 volumio sudo[18124]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:45 volumio volumio[1042]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 24 22:56:45 volumio volumio[1042]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 24 22:56:45 volumio volumio[1042]: info: Reading ALSA contributions from plugins. May 24 22:56:45 volumio systemd[1]: Stopping FusionDsp Daemon... May 24 22:56:45 volumio volumio[6210]: Backend configuration: May 24 22:56:45 volumio volumio[6210]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 24 22:56:45 volumio volumio[6210]: active_config_txt: null May 24 22:56:45 volumio volumio[6210]: camilla_host: 127.0.0.1 May 24 22:56:45 volumio volumio[6210]: camilla_port: 9876 May 24 22:56:45 volumio volumio[6210]: coeff_dir: /data/INTERNAL/FusionDsp/filters May 24 22:56:45 volumio volumio[6210]: config_dir: /data/configuration/audio_interface/fusiondsp May 24 22:56:45 volumio volumio[6210]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 24 22:56:45 volumio volumio[6210]: log_file: /tmp/camilladsp.log May 24 22:56:45 volumio volumio[6210]: on_get_active_config: null May 24 22:56:45 volumio volumio[6210]: on_set_active_config: null May 24 22:56:45 volumio volumio[6210]: port: 5011 May 24 22:56:45 volumio volumio[6210]: supported_capture_types: null May 24 22:56:45 volumio volumio[6210]: supported_playback_types: null May 24 22:56:45 volumio volumio[6210]: update_config_symlink: false May 24 22:56:45 volumio volumio[6210]: update_config_txt: false May 24 22:56:45 volumio volumio[6210]: ======== Running on http://0.0.0.0:5011 ======== May 24 22:56:45 volumio volumio[6210]: (Press CTRL+C to quit) May 24 22:56:45 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:56:45 volumio volumio[1042]: info: Asound.conf file written May 24 22:56:45 volumio sudo[18127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 24 22:56:45 volumio sudo[18127]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:45 volumio sudo[18127]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio systemd[1]: fusiondsp.service: Succeeded. May 24 22:56:46 volumio systemd[1]: Stopped FusionDsp Daemon. May 24 22:56:46 volumio volumio[1042]: xcb_connection_has_error() returned true May 24 22:56:46 volumio volumio[1042]: info: Output device has changed, restarting MPD May 24 22:56:46 volumio sudo[18124]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio sudo[18132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 22:56:46 volumio sudo[18132]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio sudo[18132]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio volumio[1042]: info: Output device has changed, restarting Shairport Sync May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:56:46 volumio sudo[18135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 22:56:46 volumio sudo[18135]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio systemd[1]: Stopping Music Player Daemon... May 24 22:56:46 volumio volumio[1042]: xcb_connection_has_error() returned true May 24 22:56:46 volumio volumio[1042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 22:56:46 volumio volumio[1042]: xcb_connection_has_error() returned true May 24 22:56:46 volumio volumio[1042]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio systemd[1]: mpd.service: Succeeded. May 24 22:56:46 volumio systemd[1]: Stopped Music Player Daemon. May 24 22:56:46 volumio volumio[1042]: info: QobuzConnect: setDeactiveState invoked May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:46 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:46 volumio vtcs[6600]: [2025-05-24 22:56:46.152] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 24 22:56:46 volumio vtcs[6600]: [2025-05-24 22:56:46.152] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 24 22:56:46 volumio volumio[1042]: info: Done. May 24 22:56:46 volumio sudo[18144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 24 22:56:46 volumio systemd[1]: Starting Music Player Daemon... May 24 22:56:46 volumio sudo[18144]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 24 22:56:46 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 24 22:56:46 volumio sudo[18149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 24 22:56:46 volumio sudo[18149]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio systemd[1]: vtcs.service: Succeeded. May 24 22:56:46 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 24 22:56:46 volumio sudo[18144]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio sudo[18149]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio sudo[18140]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 22:56:46 volumio sudo[18140]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio sudo[18140]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio sudo[18158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:56:46 volumio volumio[1042]: info: MPD Permissions set May 24 22:56:46 volumio volumio[1042]: info: FusionDsp - Reporting Fusion DSP Disabled May 24 22:56:46 volumio volumio[1042]: info: Removing fusiondspeq DSP Signal Path Element May 24 22:56:46 volumio sudo[18158]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio sudo[18158]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio sudo[18164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 24 22:56:46 volumio sudo[18164]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... May 24 22:56:46 volumio qobuz-connect[6374]: 20250524 22:56:46.369 [6374.6374] INFO SampleApp: Stopping Local configuration server May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:56:46 volumio qobuz-connect[6374]: 20250524 22:56:46.379 [6374.6374] INFO SampleApp: shat down connection on UNIX socket May 24 22:56:46 volumio systemd[1]: qobuz-connect.service: Succeeded. May 24 22:56:46 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. May 24 22:56:46 volumio systemd[1]: Started Volumio Qobuz Connect Service. May 24 22:56:46 volumio sudo[18164]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio volumio[1042]: info: Starting Shairport Sync May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 22:56:46 volumio volumio[1042]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 24 22:56:46 volumio volumio[1042]: info: QobuzConnect: setDeactiveState invoked May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:46 volumio sudo[18185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 22:56:46 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:46 volumio sudo[18185]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio volumio[1042]: info: Preparing to generate the ALSA configuration file May 24 22:56:46 volumio volumio[1042]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 24 22:56:46 volumio volumio[1042]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 24 22:56:46 volumio volumio[1042]: info: Reading ALSA contributions from plugins. May 24 22:56:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 22:56:46 volumio systemd[1]: shairport-sync.service: Succeeded. May 24 22:56:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 22:56:46 volumio volumio[1042]: info: Executing endpoint qc_getconfig May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.481 [18176.18176] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.486 [18176.18176] INFO VolumeManager: [0x4dc5b0]: Setting new playback volume: 75 May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.486 [18176.18176] INFO VolumeManager: [0x4dc5b0]: Setting new mute state: 0 May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.486 [18176.18176] INFO QobuzConnect: [0x4dcf80]: Client initialized! May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.486 [18176.18176] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.497 [18176.18176] INFO LocalConfigManager: [0x4dbe90]: Starting Local Configuration server May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.497 [18176.18176] INFO SampleApp: Starting Local configuration server May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.497 [18176.18176] INFO SampleApp: Connected to UNIX socket client 0x4d1f60 May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 22:56:46 volumio volumio[1042]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 24 22:56:46 volumio volumio[1042]: info: QobuzConnect: QOBUZ Connect daemon connected May 24 22:56:46 volumio volumio[1042]: info: Asound.conf file written May 24 22:56:46 volumio sudo[18197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 24 22:56:46 volumio sudo[18197]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 22:56:46 volumio sudo[18185]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio sudo[18197]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio volumio[1042]: xcb_connection_has_error() returned true May 24 22:56:46 volumio volumio[1042]: info: Output device has changed, restarting MPD May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.631 [18176.18176] INFO SampleApp: Playback volume changed: 75 May 24 22:56:46 volumio sudo[18205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 22:56:46 volumio sudo[18205]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio sudo[18205]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio volumio[1042]: info: Output device has changed, restarting Shairport Sync May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:56:46 volumio sudo[18208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 22:56:46 volumio sudo[18208]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 24 22:56:46 volumio systemd[1]: mpd.service: Succeeded. May 24 22:56:46 volumio systemd[1]: Stopped Music Player Daemon. May 24 22:56:46 volumio volumio[1042]: xcb_connection_has_error() returned true May 24 22:56:46 volumio volumio[1042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 22:56:46 volumio volumio[1042]: xcb_connection_has_error() returned true May 24 22:56:46 volumio volumio[1042]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: QobuzConnect: setDeactiveState invoked May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:46 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:46 volumio systemd[1]: Starting Music Player Daemon... May 24 22:56:46 volumio sudo[18217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 24 22:56:46 volumio sudo[18217]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio sudo[18220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 24 22:56:46 volumio sudo[18220]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio sudo[18217]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio sudo[18213]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 22:56:46 volumio sudo[18213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio sudo[18213]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio sudo[18220]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio sudo[18229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 24 22:56:46 volumio sudo[18229]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:56:46 volumio sudo[18229]: pam_unix(sudo:session): session closed for user root May 24 22:56:46 volumio sudo[18232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 24 22:56:46 volumio sudo[18232]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:46 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:46 volumio volumio[1042]: info: MPD Permissions set May 24 22:56:46 volumio volumio[1042]: info: Shairport-Sync Started May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.945 [18176.18176] INFO SampleApp: Stopping Local configuration server May 24 22:56:46 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... May 24 22:56:46 volumio qobuz-connect[18176]: 20250524 22:56:46.955 [18176.18176] INFO SampleApp: shat down connection on UNIX socket May 24 22:56:46 volumio systemd[1]: qobuz-connect.service: Succeeded. May 24 22:56:46 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:56:46 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 22:56:46 volumio volumio[1042]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 24 22:56:46 volumio volumio[1042]: info: QobuzConnect: setDeactiveState invoked May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:46 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:46 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:47 volumio systemd[1]: Started Volumio Qobuz Connect Service. May 24 22:56:47 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:56:47 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:56:47 volumio sudo[18232]: pam_unix(sudo:session): session closed for user root May 24 22:56:47 volumio volumio[1042]: info: Starting Shairport Sync May 24 22:56:47 volumio volumio[1042]: info: Executing endpoint qc_getconfig May 24 22:56:47 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 24 22:56:47 volumio qobuz-connect[18235]: 20250524 22:56:47.084 [18235.18235] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 24 22:56:47 volumio volumio[1042]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 24 22:56:47 volumio volumio[1042]: info: QobuzConnect: QOBUZ Connect daemon connected May 24 22:56:47 volumio qobuz-connect[18235]: 20250524 22:56:47.089 [18235.18235] INFO VolumeManager: [0x17c75b0]: Setting new playback volume: 75 May 24 22:56:47 volumio qobuz-connect[18235]: 20250524 22:56:47.089 [18235.18235] INFO VolumeManager: [0x17c75b0]: Setting new mute state: 0 May 24 22:56:47 volumio qobuz-connect[18235]: 20250524 22:56:47.089 [18235.18235] INFO QobuzConnect: [0x17c7f80]: Client initialized! May 24 22:56:47 volumio qobuz-connect[18235]: 20250524 22:56:47.089 [18235.18235] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 24 22:56:47 volumio sudo[18255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 22:56:47 volumio sudo[18255]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:47 volumio qobuz-connect[18235]: 20250524 22:56:47.113 [18235.18235] INFO LocalConfigManager: [0x17c6e90]: Starting Local Configuration server May 24 22:56:47 volumio qobuz-connect[18235]: 20250524 22:56:47.114 [18235.18235] INFO SampleApp: Starting Local configuration server May 24 22:56:47 volumio qobuz-connect[18235]: 20250524 22:56:47.114 [18235.18235] INFO SampleApp: Connected to UNIX socket client 0x17bcf60 May 24 22:56:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 22:56:47 volumio systemd[1]: shairport-sync.service: Succeeded. May 24 22:56:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 22:56:47 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:56:47 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 22:56:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 22:56:47 volumio sudo[18255]: pam_unix(sudo:session): session closed for user root May 24 22:56:47 volumio volumio[1042]: info: Shairport-Sync Started May 24 22:56:47 volumio volumio[1042]: (node:1042) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 24 22:56:47 volumio volumio[1042]: (Use `node --trace-warnings ...` to show where the warning was created) May 24 22:56:47 volumio volumio[1042]: (node:1042) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 24 22:56:47 volumio volumio[1042]: (node:1042) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 24 22:56:47 volumio volumio[1042]: (node:1042) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 24 22:56:47 volumio volumio[1042]: (node:1042) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 24 22:56:47 volumio qobuz-connect[18235]: 20250524 22:56:47.234 [18235.18235] INFO SampleApp: Playback volume changed: 75 May 24 22:56:47 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:47 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:47 volumio mpd[18228]: May 24 22:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 22:56:47 volumio systemd[1]: Started Music Player Daemon. May 24 22:56:47 volumio sudo[18135]: pam_unix(sudo:session): session closed for user root May 24 22:56:47 volumio sudo[18208]: pam_unix(sudo:session): session closed for user root May 24 22:56:47 volumio volumio[1042]: error: MPD error: The expression evaluated to a falsy value: May 24 22:56:47 volumio volumio[1042]: assert.ok(self.idling) May 24 22:56:47 volumio volumio[1042]: error: The expression evaluated to a falsy value: May 24 22:56:47 volumio volumio[1042]: assert.ok(self.idling) May 24 22:56:47 volumio volumio[1042]: error: updateQueue error: null May 24 22:56:49 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 24 22:56:49 volumio volumio[1042]: info: TidalConnect service stoped! May 24 22:56:49 volumio volumio[1042]: info: TidalConnect service stoped! May 24 22:56:49 volumio volumio[1042]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 24 22:56:49 volumio volumio[1042]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 24 22:56:49 volumio sudo[18616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 24 22:56:49 volumio sudo[18616]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:49 volumio systemd[1]: Started Volumio Tidal Connect Service. May 24 22:56:49 volumio sudo[18616]: pam_unix(sudo:session): session closed for user root May 24 22:56:49 volumio volumio[1042]: info: Executing endpoint tc_getconfig May 24 22:56:49 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 24 22:56:49 volumio vtcs[18625]: STARTING TidalConnect services, version: 1.4.0.34 May 24 22:56:49 volumio vtcs[18625]: STARTED TidalConnect services. May 24 22:56:49 volumio volumio[1042]: info: Executing endpoint tc_connect May 24 22:56:49 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 24 22:56:49 volumio volumio[1042]: info: Connecting to TidalConnect May 24 22:56:49 volumio volumio[1042]: info: CoreCommandRouter::servicePushState May 24 22:56:49 volumio volumio[1042]: info: CoreStateMachine::pushState May 24 22:56:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:49 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:56:49 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState May 24 22:56:49 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device May 24 22:56:49 volumio volumio[1042]: info: MRS: Pushing multiroomSync output May 24 22:56:49 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:49 volumio volumio[1042]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 24 22:56:49 volumio volumio[1042]: info: CoreCommandRouter::servicePushState May 24 22:56:49 volumio volumio[1042]: info: CoreStateMachine::pushState May 24 22:56:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:49 volumio volumio[1042]: info: CoreCommandRouter::volumioPushState May 24 22:56:49 volumio volumio[1042]: info: MRS: Pushing multiroomSync output update for this device May 24 22:56:49 volumio volumio[1042]: info: MRS: Pushing multiroomSync output May 24 22:56:49 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:49 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:49 volumio volumio[1042]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 24 22:56:49 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 24 22:56:49 volumio volumio[1042]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 24 22:56:49 volumio volumio[1042]: info: TidalConnect service stoped! May 24 22:56:49 volumio volumio[1042]: info: TidalConnect service stoped! May 24 22:56:49 volumio volumio[1042]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 24 22:56:49 volumio volumio[1042]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 24 22:56:49 volumio sudo[18730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 24 22:56:50 volumio sudo[18730]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:56:50 volumio sudo[18730]: pam_unix(sudo:session): session closed for user root May 24 22:56:51 volumio volumio[1042]: info: CoreCommandRouter::volumioPause May 24 22:56:51 volumio volumio[1042]: info: CoreStateMachine::pause May 24 22:56:51 volumio volumio[1042]: info: CoreStateMachine::stPlaybackTimer May 24 22:56:51 volumio volumio[1042]: info: CoreStateMachine::servicePause May 24 22:56:51 volumio volumio[1042]: info: CorePlayQueue::getTrack 9 May 24 22:56:51 volumio volumio[1042]: info: CoreCommandRouter::servicePause May 24 22:56:51 volumio volumio[1042]: info: [1748098611318] ControllerTidal::pause May 24 22:56:51 volumio volumio[1042]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 22:56:51 volumio volumio[1042]: info: ControllerMpd::pause May 24 22:56:51 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand pause May 24 22:56:51 volumio volumio[1042]: info: sendMpdCommand pause took 1 milliseconds May 24 22:56:52 volumio volumio[1042]: info: TidalConnect service started! May 24 22:56:52 volumio volumio[1042]: info: CoreCommandRouter::volumioPause May 24 22:56:52 volumio volumio[1042]: info: CoreStateMachine::pause May 24 22:56:53 volumio volumio[1042]: info: TidalConnect service started! May 24 22:56:56 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 22:56:56 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 22:56:56 volumio volumio[1042]: info: Discovery: Getting this device information May 24 22:56:56 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:56 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 22:56:57 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 22:56:57 volumio volumio[1042]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 24 22:56:57 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 24 22:56:57 volumio volumio[1042]: info: Received Get System Version May 24 22:56:57 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 22:56:57 volumio volumio[1042]: info: Received Get System Info May 24 22:56:57 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 22:56:57 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 22:56:57 volumio volumio[1042]: info: Discovery: Getting this device information May 24 22:56:57 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:56:57 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 22:56:57 volumio volumio[1042]: info: CoreCommandRouter::volumioNext May 24 22:56:57 volumio volumio[1042]: info: CoreStateMachine::next May 24 22:56:57 volumio volumio[1042]: info: ControllerMpd::next May 24 22:56:57 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand next May 24 22:56:58 volumio volumio[1042]: info: CoreCommandRouter::volumioNext May 24 22:56:58 volumio volumio[1042]: info: CoreStateMachine::next May 24 22:56:58 volumio volumio[1042]: info: ControllerMpd::next May 24 22:56:58 volumio volumio[1042]: verbose: ControllerMpd::sendMpdCommand next May 24 22:57:01 volumio go-librespot[4532]: time="2025-05-24T22:57:01+08:00" level=trace msg="sent dealer ping" May 24 22:57:01 volumio go-librespot[4532]: time="2025-05-24T22:57:01+08:00" level=trace msg="received dealer pong" May 24 22:57:02 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 22:57:02 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 24 22:57:06 volumio volumio[1042]: info: Starting Uninstall of plugin audio_interface - fusiondsp May 24 22:57:06 volumio volumio[1042]: info: Uninstalling plugin fusiondsp May 24 22:57:06 volumio volumio[1042]: info: Disabling plugin fusiondsp May 24 22:57:06 volumio volumio[1042]: info: Preparing to generate the ALSA configuration file May 24 22:57:06 volumio volumio[1042]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 24 22:57:06 volumio volumio[1042]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 24 22:57:06 volumio volumio[1042]: info: Reading ALSA contributions from plugins. May 24 22:57:06 volumio volumio[1042]: info: Asound.conf file unchanged, so no further update is needed May 24 22:57:06 volumio volumio[1042]: info: Output device has changed, restarting MPD May 24 22:57:06 volumio sudo[21370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 22:57:06 volumio sudo[21370]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio sudo[21370]: pam_unix(sudo:session): session closed for user root May 24 22:57:06 volumio volumio[1042]: info: Output device has changed, restarting Shairport Sync May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:57:06 volumio sudo[21373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 22:57:06 volumio sudo[21373]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio systemd[1]: Stopping Music Player Daemon... May 24 22:57:06 volumio volumio[1042]: xcb_connection_has_error() returned true May 24 22:57:06 volumio systemd[1]: mpd.service: Succeeded. May 24 22:57:06 volumio volumio[1042]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 24 22:57:06 volumio volumio[1042]: xcb_connection_has_error() returned true May 24 22:57:06 volumio volumio[1042]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 24 22:57:06 volumio systemd[1]: Stopped Music Player Daemon. May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: QobuzConnect: setDeactiveState invoked May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:57:06 volumio vtcs[18625]: [2025-05-24 22:57:06.212] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 24 22:57:06 volumio vtcs[18625]: [2025-05-24 22:57:06.212] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 24 22:57:06 volumio sudo[21382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 24 22:57:06 volumio systemd[1]: Starting Music Player Daemon... May 24 22:57:06 volumio volumio[1042]: info: Checking if uninstall.sh is present May 24 22:57:06 volumio sudo[21382]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio systemd[1]: Stopping Volumio Tidal Connect Service... May 24 22:57:06 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 24 22:57:06 volumio sudo[21385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 24 22:57:06 volumio sudo[21385]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio systemd[1]: vtcs.service: Succeeded. May 24 22:57:06 volumio systemd[1]: Stopped Volumio Tidal Connect Service. May 24 22:57:06 volumio sudo[21382]: pam_unix(sudo:session): session closed for user root May 24 22:57:06 volumio sudo[21385]: pam_unix(sudo:session): session closed for user root May 24 22:57:06 volumio sudo[21378]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 24 22:57:06 volumio sudo[21378]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio volumio[1042]: info: Executing uninstall.sh May 24 22:57:06 volumio sudo[21378]: pam_unix(sudo:session): session closed for user root May 24 22:57:06 volumio sudo[21394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 24 22:57:06 volumio sudo[21394]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:57:06 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 22:57:06 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:57:06 volumio sudo[21394]: pam_unix(sudo:session): session closed for user root May 24 22:57:06 volumio sudo[21400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 24 22:57:06 volumio sudo[21400]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:57:06 volumio sudo[21399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh May 24 22:57:06 volumio sudo[21399]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio volumio[1042]: info: MPD Permissions set May 24 22:57:06 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... May 24 22:57:06 volumio qobuz-connect[18235]: 20250524 22:57:06.421 [18235.18235] INFO SampleApp: Stopping Local configuration server May 24 22:57:06 volumio qobuz-connect[18235]: 20250524 22:57:06.431 [18235.18235] INFO SampleApp: shat down connection on UNIX socket May 24 22:57:06 volumio systemd[1]: qobuz-connect.service: Succeeded. May 24 22:57:06 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. May 24 22:57:06 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 22:57:06 volumio volumio[1042]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 24 22:57:06 volumio volumio[1042]: info: QobuzConnect: setDeactiveState invoked May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:57:06 volumio volumio[1042]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 22:57:06 volumio systemd[1]: Started Volumio Qobuz Connect Service. May 24 22:57:06 volumio sudo[21400]: pam_unix(sudo:session): session closed for user root May 24 22:57:06 volumio volumio[1042]: info: Starting Shairport Sync May 24 22:57:06 volumio sudo[21419]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /lib/systemd/system/fusiondsp.service May 24 22:57:06 volumio sudo[21419]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio sudo[21419]: pam_unix(sudo:session): session closed for user root May 24 22:57:06 volumio sudo[21428]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 24 22:57:06 volumio sudo[21428]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio volumio[1042]: info: Executing endpoint qc_getconfig May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 24 22:57:06 volumio qobuz-connect[21406]: 20250524 22:57:06.580 [21406.21406] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 24 22:57:06 volumio sudo[21429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 24 22:57:06 volumio sudo[21429]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:57:06 volumio volumio[1042]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 24 22:57:06 volumio volumio[1042]: info: QobuzConnect: QOBUZ Connect daemon connected May 24 22:57:06 volumio qobuz-connect[21406]: 20250524 22:57:06.586 [21406.21406] INFO VolumeManager: [0xf2d5b0]: Setting new playback volume: 75 May 24 22:57:06 volumio qobuz-connect[21406]: 20250524 22:57:06.587 [21406.21406] INFO VolumeManager: [0xf2d5b0]: Setting new mute state: 0 May 24 22:57:06 volumio qobuz-connect[21406]: 20250524 22:57:06.587 [21406.21406] INFO QobuzConnect: [0xf2df80]: Client initialized! May 24 22:57:06 volumio qobuz-connect[21406]: 20250524 22:57:06.587 [21406.21406] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 24 22:57:06 volumio systemd[1]: Reloading. May 24 22:57:06 volumio qobuz-connect[21406]: 20250524 22:57:06.604 [21406.21406] INFO LocalConfigManager: [0xf2ce90]: Starting Local Configuration server May 24 22:57:06 volumio qobuz-connect[21406]: 20250524 22:57:06.604 [21406.21406] INFO SampleApp: Starting Local configuration server May 24 22:57:06 volumio qobuz-connect[21406]: 20250524 22:57:06.605 [21406.21406] INFO SampleApp: Connected to UNIX socket client 0xf22f60 May 24 22:57:06 volumio qobuz-connect[21406]: 20250524 22:57:06.730 [21406.21406] INFO SampleApp: Playback volume changed: 75 May 24 22:57:06 volumio volumio[1042]: info: CoreCommandRouter::volumioGetState May 24 22:57:06 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 24 22:57:07 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 24 22:57:07 volumio mpd[21395]: May 24 22:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 24 22:57:07 volumio systemd[1]: Started Music Player Daemon. May 24 22:57:07 volumio sudo[21428]: pam_unix(sudo:session): session closed for user root May 24 22:57:07 volumio sudo[21373]: pam_unix(sudo:session): session closed for user root May 24 22:57:07 volumio sudo[21399]: pam_unix(sudo:session): session closed for user root May 24 22:57:07 volumio volumio[1042]: info: Uninstall script completed May 24 22:57:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 24 22:57:07 volumio systemd[1]: shairport-sync.service: Succeeded. May 24 22:57:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 24 22:57:07 volumio volumio[1042]: info: Removing plugin fusiondsp from configuration May 24 22:57:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 24 22:57:07 volumio sudo[21429]: pam_unix(sudo:session): session closed for user root May 24 22:57:07 volumio volumio[1042]: info: Successfully removed fusiondsp configuration files May 24 22:57:07 volumio volumio[1042]: info: Plugin folders cleanup May 24 22:57:07 volumio volumio[1042]: info: Scanning into folder /volumio/app/plugins/ May 24 22:57:07 volumio volumio[1042]: info: Scanning category audio_interface May 24 22:57:07 volumio volumio[1042]: info: Scanning category miscellanea May 24 22:57:07 volumio volumio[1042]: info: Scanning category music_service May 24 22:57:07 volumio volumio[1042]: info: Scanning category plugins.json May 24 22:57:07 volumio volumio[1042]: info: Scanning category system_controller May 24 22:57:07 volumio volumio[1042]: info: Scanning category user_interface May 24 22:57:07 volumio volumio[1042]: info: Scanning into folder /data/plugins/ May 24 22:57:07 volumio volumio[1042]: info: Scanning category audio_interface May 24 22:57:07 volumio volumio[1042]: info: Cleaning folder for fusiondsp May 24 22:57:07 volumio volumio[1042]: info: Scanning category music_service May 24 22:57:07 volumio volumio[1042]: info: Scanning category system_controller May 24 22:57:07 volumio volumio[1042]: info: Scanning category user_interface May 24 22:57:07 volumio volumio[1042]: info: Plugin folders cleanup completed May 24 22:57:07 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 24 22:57:07 volumio volumio[1042]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Volusonic","name":"volusonic","category":"music_service","version":"2.3.0","icon":"fa-ship","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"ScheduledRestart","name":"scheduledrestart","category":"system_controller","version":"0.7.2","icon":"fa-clock-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.5","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Randomizer","name":"randomizer","category":"user_interface","version":"3.1.2","icon":"fa-random","isManuallyInstalled":false,"enabled":false,"active":false}] May 24 22:57:07 volumio volumio[1042]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 24 22:57:07 volumio volumio[1042]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 22:57:07 volumio volumio[1042]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 24 22:57:07 volumio volumio[1042]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 24 22:57:07 volumio volumio[1042]: at onErrorNT (internal/child_process.js:465:16) May 24 22:57:07 volumio volumio[1042]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 24 22:57:07 volumio volumio[1042]: errno: -2, May 24 22:57:07 volumio volumio[1042]: code: 'ENOENT', May 24 22:57:07 volumio volumio[1042]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 24 22:57:07 volumio volumio[1042]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 24 22:57:07 volumio volumio[1042]: spawnargs: [ May 24 22:57:07 volumio volumio[1042]: '-p', May 24 22:57:07 volumio volumio[1042]: 9876, May 24 22:57:07 volumio volumio[1042]: '-o', May 24 22:57:07 volumio volumio[1042]: '/tmp/camilladsp.log', May 24 22:57:07 volumio volumio[1042]: '-l', May 24 22:57:07 volumio volumio[1042]: 'warn', May 24 22:57:07 volumio volumio[1042]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 24 22:57:07 volumio volumio[1042]: ] May 24 22:57:07 volumio volumio[1042]: } May 24 22:57:07 volumio volumio[1042]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 22:57:07 volumio sudo[21543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 22:56 May 24 22:57:07 volumio sudo[21543]: 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"