Dec 27 13:38:00 netransportnew go-librespot[4433]: time="2025-12-27T13:38:00+01:00" level=debug msg="handling resume player command from c440b86926bbf484350ffd2778a1090c27242a99" Dec 27 13:38:00 netransportnew go-librespot[4433]: time="2025-12-27T13:38:00+01:00" level=trace msg="seek to 154258ms (diff: 114ms, samples: 6802777, bytes: 6975778)" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:38:00 netransportnew go-librespot[4433]: time="2025-12-27T13:38:00+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Dec 27 13:38:00 netransportnew go-librespot[4433]: time="2025-12-27T13:38:00+01:00" level=debug msg="resume track at 154144ms" Dec 27 13:38:00 netransportnew go-librespot[4433]: time="2025-12-27T13:38:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 27 13:38:00 netransportnew go-librespot[4433]: time="2025-12-27T13:38:00+01:00" level=trace msg="scheduling prefetch in 39s" Dec 27 13:38:00 netransportnew go-librespot[4433]: time="2025-12-27T13:38:00+01:00" level=debug msg="sending successful reply for dealer request" Dec 27 13:38:00 netransportnew go-librespot[4433]: time="2025-12-27T13:38:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 27 13:38:00 netransportnew go-librespot[4433]: time="2025-12-27T13:38:00+01:00" level=trace msg="emitting websocket event: playing" Dec 27 13:38:00 netransportnew volumio[1272]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:search:bilderbuch","uri":"spotify:track:0xCXcq4y44uHJtJ7zZDF3d","resume":true,"play_origin":"search"}} Dec 27 13:38:00 netransportnew volumio[1272]: SPOTIFY: PUSH STATE SPOTIFY Dec 27 13:38:00 netransportnew volumio[1272]: SPOTIFY: {"status":"play","service":"spop","title":"Bungalow","artist":"Bilderbuch","album":"Magic Life","albumart":"https://i.scdn.co/image/ab67616d00001e02bf9749fc534464d100af39d8","uri":"spotify:track:0xCXcq4y44uHJtJ7zZDF3d","trackType":"spotify","seek":154614,"duration":222,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 27 13:38:00 netransportnew volumio[1272]: info: CoreCommandRouter::servicePushState Dec 27 13:38:00 netransportnew volumio[1272]: info: CoreStateMachine::pushState Dec 27 13:38:00 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:38:00 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:38:00 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:38:00 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:38:00 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:00 netransportnew volumio[1272]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 27 13:38:01 netransportnew volumio[1272]: SPOTIFY: PUSH STATE SPOTIFY Dec 27 13:38:01 netransportnew volumio[1272]: SPOTIFY: {"status":"play","service":"spop","title":"Bungalow","artist":"Bilderbuch","album":"Magic Life","albumart":"https://i.scdn.co/image/ab67616d00001e02bf9749fc534464d100af39d8","uri":"spotify:track:0xCXcq4y44uHJtJ7zZDF3d","trackType":"spotify","seek":154614,"duration":222,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 27 13:38:01 netransportnew volumio[1272]: info: CoreCommandRouter::servicePushState Dec 27 13:38:01 netransportnew volumio[1272]: info: CoreStateMachine::pushState Dec 27 13:38:01 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:38:01 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:38:01 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:38:01 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:04 netransportnew volumio[1272]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.83 UA: Mozilla/5.0 (Linux; Android 15; 23090RA98G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 27 13:38:04 netransportnew volumio[1272]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.83 UA: Mozilla/5.0 (Linux; Android 15; 23090RA98G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 13:38:04 netransportnew volumio[1272]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 27 13:38:04 netransportnew volumio[1272]: info: Received Get System Info Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:38:04 netransportnew volumio[1272]: info: Discovery: Getting this device information Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:04 netransportnew volumio[1272]: info: Listing playlists Dec 27 13:38:04 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 27 13:38:05 netransportnew volumio[1272]: verbose: New Socket.io Connection to 192.168.1.43:3000 from 192.168.1.83 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Dec 27 13:38:05 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:38:05 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:38:05 netransportnew volumio[1272]: info: Discovery: Getting this device information Dec 27 13:38:05 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:05 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:38:05 netransportnew sudo[5676]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 13:38:05 netransportnew sudo[5676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:05 netransportnew sudo[5676]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:05 netransportnew sudo[5678]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 13:38:05 netransportnew sudo[5678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:05 netransportnew sudo[5678]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:05 netransportnew volumio[1272]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.83 UA: Mozilla/5.0 (Linux; Android 15; 23090RA98G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Dec 27 13:38:05 netransportnew sudo[5682]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 13:38:05 netransportnew sudo[5682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:05 netransportnew sudo[5682]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:05 netransportnew sudo[5684]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 13:38:05 netransportnew sudo[5684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:05 netransportnew sudo[5684]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:05 netransportnew volumio[1272]: verbose: New Socket.io Connection to 192.168.1.43 from 192.168.1.83 UA: Mozilla/5.0 (Linux; Android 15; 23090RA98G Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 27 13:38:06 netransportnew volumio[1272]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 27 13:38:06 netransportnew volumio[1272]: info: Received Get System Info Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:38:06 netransportnew volumio[1272]: info: Discovery: Getting this device information Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:06 netransportnew volumio[1272]: info: Listing playlists Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 13:38:06 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 27 13:38:06 netransportnew volumio[1272]: verbose: New Socket.io Connection to 192.168.1.43:3000 from 192.168.1.83 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Dec 27 13:38:07 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 27 13:38:07 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 13:38:07 netransportnew volumio[1272]: info: Received Get System Info Dec 27 13:38:07 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:38:07 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:38:07 netransportnew volumio[1272]: info: Discovery: Getting this device information Dec 27 13:38:07 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:07 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:38:08 netransportnew go-librespot[4433]: time="2025-12-27T13:38:08+01:00" level=debug msg="fetched chunk 17/18, size: 524288" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:38:08 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 13:38:08 netransportnew volumio[1272]: info: Received Get System Info Dec 27 13:38:08 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:38:08 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:38:08 netransportnew volumio[1272]: info: Discovery: Getting this device information Dec 27 13:38:08 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:08 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:38:08 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 27 13:38:08 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 27 13:38:14 netransportnew go-librespot[4433]: time="2025-12-27T13:38:14+01:00" level=trace msg="sent dealer ping" Dec 27 13:38:14 netransportnew go-librespot[4433]: time="2025-12-27T13:38:14+01:00" level=trace msg="received dealer pong" Dec 27 13:38:15 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 27 13:38:17 netransportnew volumio[1272]: info: Enabling plugin peppyspectrum Dec 27 13:38:17 netransportnew volumio[1272]: info: Loading plugin "peppyspectrum"... Dec 27 13:38:17 netransportnew volumio[1272]: info: Preparing to generate the ALSA configuration file Dec 27 13:38:17 netransportnew volumio[1272]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 27 13:38:17 netransportnew volumio[1272]: info: Reading ALSA contributions from plugins. Dec 27 13:38:17 netransportnew volumio[1272]: info: Asound.conf file unchanged, so no further update is needed Dec 27 13:38:17 netransportnew volumio[1272]: info: Output device has changed, restarting MPD Dec 27 13:38:17 netransportnew volumio[1272]: info: Output device has changed, restarting Shairport Sync Dec 27 13:38:17 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:17 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:38:17 netransportnew sudo[5706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 13:38:17 netransportnew sudo[5706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:17 netransportnew sudo[5706]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:17 netransportnew sudo[5708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 13:38:17 netransportnew sudo[5708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:17 netransportnew volumio[1272]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 13:38:17 netransportnew volumio[1272]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 13:38:17 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:17 netransportnew volumio[1272]: info: QobuzConnect: setDeactiveState invoked Dec 27 13:38:17 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:17 netransportnew vtcs[5631]: [2025-12-27 13:38:17.947] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 27 13:38:17 netransportnew vtcs[5631]: [2025-12-27 13:38:17.948] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 27 13:38:17 netransportnew volumio[1272]: info: PLUGIN START: peppyspectrum Dec 27 13:38:17 netransportnew systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 27 13:38:17 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 27 13:38:17 netransportnew sudo[5718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 13:38:17 netransportnew sudo[5718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:17 netransportnew systemd[1]: mpd.service: Deactivated successfully. Dec 27 13:38:17 netransportnew systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 13:38:17 netransportnew systemd[1]: mpd.service: Consumed 1.525s CPU time. Dec 27 13:38:17 netransportnew systemd[1]: mpd.socket: Deactivated successfully. Dec 27 13:38:17 netransportnew systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 13:38:17 netransportnew systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 13:38:18 netransportnew sudo[5721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 27 13:38:18 netransportnew sudo[5721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew volumio[1272]: info: Preparing to generate the ALSA configuration file Dec 27 13:38:18 netransportnew volumio[1272]: info: Done. Dec 27 13:38:18 netransportnew sudo[5721]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 13:38:18 netransportnew systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 13:38:18 netransportnew systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 27 13:38:18 netransportnew systemd[1]: vtcs.service: Killing process 5636 (vtcs) with signal SIGKILL. Dec 27 13:38:18 netransportnew systemd[1]: vtcs.service: Deactivated successfully. Dec 27 13:38:18 netransportnew systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 27 13:38:18 netransportnew sudo[5725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 13:38:18 netransportnew sudo[5725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew sudo[5718]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew volumio[1272]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 27 13:38:18 netransportnew volumio[1272]: info: Reading ALSA contributions from plugins. Dec 27 13:38:18 netransportnew volumio[1272]: info: snd_dummy loaded Dec 27 13:38:18 netransportnew volumio[1272]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 13:38:18 netransportnew volumio[1272]: info: MPD Permissions set Dec 27 13:38:18 netransportnew volumio[1272]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew sudo[5734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 27 13:38:18 netransportnew sudo[5734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew sudo[5734]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew sudo[5725]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew sudo[5728]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 13:38:18 netransportnew sudo[5728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 13:38:18 netransportnew sudo[5728]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew sudo[5742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 27 13:38:18 netransportnew sudo[5742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew volumio[1272]: info: Starting Shairport Sync Dec 27 13:38:18 netransportnew systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 27 13:38:18 netransportnew qobuz-connect[5596]: 20251227 13:38:18.339 [5596.5596] INFO SampleApp: Stopping Local configuration server Dec 27 13:38:18 netransportnew volumio[1272]: info: Asound.conf file unchanged, so no further update is needed Dec 27 13:38:18 netransportnew volumio[1272]: info: Output device has changed, restarting MPD Dec 27 13:38:18 netransportnew sudo[5748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 13:38:18 netransportnew sudo[5748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew volumio[1272]: info: Output device has changed, restarting Shairport Sync Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:38:18 netransportnew sudo[5750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 13:38:18 netransportnew sudo[5750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew sudo[5750]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew volumio[1272]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 13:38:18 netransportnew volumio[1272]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew sudo[5754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 13:38:18 netransportnew sudo[5754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 13:38:18 netransportnew systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 13:38:18 netransportnew systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 13:38:18 netransportnew systemd[1]: shairport-sync.service: Consumed 1.637s CPU time. Dec 27 13:38:18 netransportnew volumio[1272]: info: QobuzConnect: setDeactiveState invoked Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:18 netransportnew volumio[1272]: info: Preparing to generate the ALSA configuration file Dec 27 13:38:18 netransportnew systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 13:38:18 netransportnew sudo[5748]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew sudo[5764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 13:38:18 netransportnew sudo[5764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew systemd[1]: mpd.service: Deactivated successfully. Dec 27 13:38:18 netransportnew systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 13:38:18 netransportnew systemd[1]: mpd.socket: Deactivated successfully. Dec 27 13:38:18 netransportnew systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 13:38:18 netransportnew systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 13:38:18 netransportnew systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 13:38:18 netransportnew systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 13:38:18 netransportnew sudo[5769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 13:38:18 netransportnew sudo[5769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew sudo[5764]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew volumio[1272]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 27 13:38:18 netransportnew volumio[1272]: info: Reading ALSA contributions from plugins. Dec 27 13:38:18 netransportnew volumio[1272]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 13:38:18 netransportnew volumio[1272]: info: MPD Permissions set Dec 27 13:38:18 netransportnew volumio[1272]: info: Shairport-Sync Started Dec 27 13:38:18 netransportnew sudo[5794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 27 13:38:18 netransportnew sudo[5769]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew sudo[5794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew sudo[5781]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 13:38:18 netransportnew sudo[5781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 13:38:18 netransportnew sudo[5781]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: Starting Shairport Sync Dec 27 13:38:18 netransportnew volumio[1272]: info: Asound.conf file unchanged, so no further update is needed Dec 27 13:38:18 netransportnew volumio[1272]: info: Output device has changed, restarting MPD Dec 27 13:38:18 netransportnew volumio[1272]: info: Output device has changed, restarting Shairport Sync Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:38:18 netransportnew sudo[5805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 13:38:18 netransportnew sudo[5805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew sudo[5794]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew sudo[5807]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 13:38:18 netransportnew sudo[5809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 13:38:18 netransportnew sudo[5809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew sudo[5807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew sudo[5807]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:18 netransportnew sudo[5812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 27 13:38:18 netransportnew sudo[5812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:18 netransportnew systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 13:38:18 netransportnew systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 13:38:18 netransportnew systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 13:38:18 netransportnew volumio[1272]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 13:38:18 netransportnew volumio[1272]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:18 netransportnew volumio[1272]: info: QobuzConnect: setDeactiveState invoked Dec 27 13:38:18 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:19 netransportnew systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 13:38:19 netransportnew sudo[5823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 13:38:19 netransportnew sudo[5825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 27 13:38:19 netransportnew sudo[5825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:19 netransportnew sudo[5823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:19 netransportnew sudo[5805]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:19 netransportnew systemd[1]: mpd.service: Deactivated successfully. Dec 27 13:38:19 netransportnew systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 13:38:19 netransportnew systemd[1]: mpd.socket: Deactivated successfully. Dec 27 13:38:19 netransportnew systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 13:38:19 netransportnew systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 13:38:19 netransportnew volumio[1272]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 13:38:19 netransportnew volumio[1272]: info: MPD Permissions set Dec 27 13:38:19 netransportnew volumio[1272]: info: Shairport-Sync Started Dec 27 13:38:19 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:19 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:19 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:19 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:19 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:19 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:19 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:19 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:19 netransportnew sudo[5847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 27 13:38:19 netransportnew sudo[5847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:19 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 13:38:19 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 13:38:19 netransportnew systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 13:38:19 netransportnew systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 13:38:19 netransportnew volumio[1272]: info: Starting Shairport Sync Dec 27 13:38:19 netransportnew sudo[5823]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:19 netransportnew sudo[5825]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:19 netransportnew sudo[5847]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:19 netransportnew sudo[5858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 13:38:19 netransportnew sudo[5858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:19 netransportnew systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 13:38:19 netransportnew systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 13:38:19 netransportnew systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 13:38:19 netransportnew sudo[5861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 27 13:38:19 netransportnew sudo[5861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:19 netransportnew sudo[5856]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 13:38:19 netransportnew sudo[5856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 13:38:19 netransportnew sudo[5856]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:19 netransportnew systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 13:38:19 netransportnew sudo[5858]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:19 netransportnew volumio[1272]: info: Shairport-Sync Started Dec 27 13:38:20 netransportnew qobuz-connect[5596]: 20251227 13:38:20.097 [5596.5596] INFO SampleApp: shat down connection on UNIX socket Dec 27 13:38:20 netransportnew volumio[1272]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 27 13:38:20 netransportnew volumio[1272]: info: QobuzConnect: setDeactiveState invoked Dec 27 13:38:20 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:20 netransportnew systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 27 13:38:20 netransportnew systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 27 13:38:20 netransportnew systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 27 13:38:20 netransportnew sudo[5861]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:20 netransportnew sudo[5812]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:20 netransportnew sudo[5742]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:20 netransportnew volumio[1272]: info: Executing endpoint qc_getconfig Dec 27 13:38:20 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 27 13:38:20 netransportnew qobuz-connect[5881]: 20251227 13:38:20.229 [5881.5881] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 27 13:38:20 netransportnew volumio[1272]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 27 13:38:20 netransportnew volumio[1272]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 27 13:38:20 netransportnew qobuz-connect[5881]: 20251227 13:38:20.244 [5881.5881] INFO VolumeManager: [0x1601258]: Setting new playback volume: 75 Dec 27 13:38:20 netransportnew qobuz-connect[5881]: 20251227 13:38:20.244 [5881.5881] INFO VolumeManager: [0x1601258]: Setting new mute state: 0 Dec 27 13:38:20 netransportnew qobuz-connect[5881]: 20251227 13:38:20.244 [5881.5881] INFO QobuzConnect: [0x1601c28]: Client initialized! Dec 27 13:38:20 netransportnew qobuz-connect[5881]: 20251227 13:38:20.244 [5881.5881] INFO SampleApp: Starting Avahi advertising, name: NeTransport_New, service name: _qobuz-connect._tcp Dec 27 13:38:20 netransportnew qobuz-connect[5881]: 20251227 13:38:20.257 [5881.5881] INFO LocalConfigManager: [0x1600b38]: Starting Local Configuration server Dec 27 13:38:20 netransportnew qobuz-connect[5881]: 20251227 13:38:20.257 [5881.5881] INFO SampleApp: Starting Local configuration server Dec 27 13:38:20 netransportnew qobuz-connect[5881]: 20251227 13:38:20.258 [5881.5881] INFO SampleApp: Connected to UNIX socket client 0x15eb818 Dec 27 13:38:20 netransportnew volumio[1272]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 13:38:20 netransportnew qobuz-connect[5881]: 20251227 13:38:20.378 [5881.5881] INFO SampleApp: Playback volume changed: 75 Dec 27 13:38:20 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:20 netransportnew go-librespot[4433]: time="2025-12-27T13:38:20+01:00" level=debug msg="fetched chunk 18/18, size: 368664" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:38:20 netransportnew mpd[5866]: 2025-12-27T13:38:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 13:38:20 netransportnew systemd[1]: Started mpd.service - Music Player Daemon. Dec 27 13:38:20 netransportnew sudo[5708]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:20 netransportnew sudo[5754]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:20 netransportnew sudo[5809]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:20 netransportnew volumio[1272]: error: MPD error: The expression evaluated to a falsy value: Dec 27 13:38:20 netransportnew volumio[1272]: assert.ok(self.idling) Dec 27 13:38:20 netransportnew volumio[1272]: error: The expression evaluated to a falsy value: Dec 27 13:38:20 netransportnew volumio[1272]: assert.ok(self.idling) Dec 27 13:38:20 netransportnew volumio[1272]: error: MPD error: The expression evaluated to a falsy value: Dec 27 13:38:20 netransportnew volumio[1272]: assert.ok(self.idling) Dec 27 13:38:20 netransportnew volumio[1272]: error: The expression evaluated to a falsy value: Dec 27 13:38:20 netransportnew volumio[1272]: assert.ok(self.idling) Dec 27 13:38:20 netransportnew volumio[1272]: error: updateQueue error: null Dec 27 13:38:21 netransportnew volumio[1272]: info: TidalConnect service stoped! Dec 27 13:38:21 netransportnew volumio[1272]: info: TidalConnect service stoped! Dec 27 13:38:21 netransportnew volumio[1272]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 13:38:21 netransportnew volumio[1272]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 13:38:21 netransportnew sudo[5914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 27 13:38:21 netransportnew sudo[5914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:21 netransportnew systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 27 13:38:21 netransportnew sudo[5914]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:21 netransportnew volumio[1272]: info: Executing endpoint tc_getconfig Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 27 13:38:21 netransportnew vtcs[5917]: STARTING TidalConnect services, version: 1.5.2.56 Dec 27 13:38:21 netransportnew vtcs[5917]: STARTED TidalConnect services. Dec 27 13:38:21 netransportnew volumio[1272]: info: Executing endpoint tc_connect Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 27 13:38:21 netransportnew volumio[1272]: info: Connecting to TidalConnect Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::servicePushState Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreStateMachine::pushState Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:38:21 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:38:21 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:21 netransportnew volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 27 13:38:21 netransportnew volumio[1272]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 27 13:38:21 netransportnew volumio[1272]: verbose: CURRENT POSITION 0 Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreStateMachine::syncState stateService stop Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreStateMachine::syncState currentStatus play Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:38:21 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:38:21 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreStateMachine::stPlaybackTimer Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::servicePushState Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreStateMachine::pushState Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:38:21 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:38:21 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:21 netransportnew volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 27 13:38:21 netransportnew volumio[1272]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 27 13:38:21 netransportnew volumio[1272]: verbose: CURRENT POSITION 0 Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreStateMachine::syncState stateService stop Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreStateMachine::pushState Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:38:21 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:38:21 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:21 netransportnew volumio[1272]: info: No code Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreStateMachine::pushState Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:38:21 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:38:21 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:38:21 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:21 netransportnew volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Dec 27 13:38:21 netransportnew volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Dec 27 13:38:21 netransportnew volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Dec 27 13:38:21 netransportnew sudo[5936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 13:38:21 netransportnew sudo[5936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:21 netransportnew volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Dec 27 13:38:21 netransportnew sudo[5938]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 27 13:38:21 netransportnew sudo[5938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:21 netransportnew sudo[5941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 13:38:21 netransportnew volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Dec 27 13:38:21 netransportnew volumio[1272]: info: TidalConnect service stoped! Dec 27 13:38:21 netransportnew sudo[5941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:21 netransportnew volumio[1272]: info: TidalConnect service stoped! Dec 27 13:38:21 netransportnew sudo[5936]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:21 netransportnew sudo[5943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 13:38:21 netransportnew sudo[5943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:21 netransportnew sudo[5946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Dec 27 13:38:21 netransportnew sudo[5946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:22 netransportnew sudo[5938]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:22 netransportnew volumio[1272]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Dec 27 13:38:22 netransportnew volumio[1272]: Job for peppyspectrum.service canceled. Dec 27 13:38:22 netransportnew volumio[1272]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 27 13:38:22 netransportnew volumio[1272]: info: peppyspectrum Daemon Stop Dec 27 13:38:22 netransportnew volumio[1272]: info: touch_display: Setting screensaver timeout to 60 seconds. Dec 27 13:38:22 netransportnew volumio[1272]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 13:38:22 netransportnew volumio[1272]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 13:38:22 netransportnew systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 27 13:38:22 netransportnew sudo[5943]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:22 netransportnew sudo[5941]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:22 netransportnew sudo[5965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 27 13:38:22 netransportnew sudo[5965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:22 netransportnew volumio[1272]: info: peppyspectrum Daemon Started Dec 27 13:38:22 netransportnew volumio[1272]: info: peppyspectrum Daemon Started Dec 27 13:38:22 netransportnew sudo[5946]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:22 netransportnew volumio[1272]: info: peppyspectrum Daemon Started Dec 27 13:38:22 netransportnew sudo[5965]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:22 netransportnew volumio[1272]: info: TidalConnect service stoped! Dec 27 13:38:22 netransportnew volumio[1272]: info: TidalConnect service stoped! Dec 27 13:38:22 netransportnew volumio[1272]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 27 13:38:22 netransportnew volumio[1272]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 27 13:38:22 netransportnew sudo[5978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 27 13:38:22 netransportnew sudo[5978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:22 netransportnew sudo[5978]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:23 netransportnew startpeppyspectrum.sh[5966]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 13:38:23 netransportnew sudo[5987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 13:38:23 netransportnew sudo[5987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:23 netransportnew sudo[5990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 13:38:23 netransportnew sudo[5990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:23 netransportnew systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 27 13:38:23 netransportnew systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 27 13:38:23 netransportnew systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 27 13:38:23 netransportnew sudo[5992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 27 13:38:23 netransportnew sudo[5992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:23 netransportnew systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 27 13:38:23 netransportnew mpd_monitor.sh[5994]: MPD Monitor Service: Starting MPD Monitor Service Dec 27 13:38:23 netransportnew sudo[5987]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:23 netransportnew volumio[1272]: info: Successfully started MPD Monitor Dec 27 13:38:23 netransportnew systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 27 13:38:23 netransportnew systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 27 13:38:23 netransportnew systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 27 13:38:23 netransportnew systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 27 13:38:23 netransportnew sudo[5990]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:23 netransportnew sudo[5992]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:23 netransportnew mpd_monitor.sh[5999]: MPD Monitor Service: Starting MPD Monitor Service Dec 27 13:38:23 netransportnew volumio[1272]: info: Successfully started MPD Monitor Dec 27 13:38:23 netransportnew volumio[1272]: info: Successfully started MPD Monitor Dec 27 13:38:24 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 13:38:24 netransportnew volumio[1272]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 27 13:38:24 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 27 13:38:24 netransportnew volumio[1272]: info: Received Get System Version Dec 27 13:38:24 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 13:38:24 netransportnew volumio[1272]: info: Received Get System Info Dec 27 13:38:24 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 13:38:24 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 13:38:24 netransportnew volumio[1272]: info: Discovery: Getting this device information Dec 27 13:38:24 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:38:24 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 13:38:24 netransportnew volumio[1272]: info: TidalConnect service started! Dec 27 13:38:25 netransportnew volumio[1272]: info: TidalConnect service started! Dec 27 13:38:25 netransportnew volumio[1272]: info: TidalConnect service started! Dec 27 13:38:38 netransportnew volumio[1272]: info: CALLMETHOD: user_interface peppyspectrum delayspectrum [object Object] Dec 27 13:38:38 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , delayspectrum Dec 27 13:38:38 netransportnew volumio[1272]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 27 13:38:38 netransportnew volumio[1272]: info: PeppySpectrum -----------------------spectrums.6 Dec 27 13:38:38 netransportnew volumio[1272]: info: PeppySpectrum -----------------------$basefolder Dec 27 13:38:38 netransportnew volumio[1272]: info: PeppySpectrum -----------------------screensize1280x400 Dec 27 13:38:38 netransportnew volumio[1272]: info: PeppySpectrum -----------------------screenwidth Dec 27 13:38:38 netransportnew volumio[1272]: info: PeppySpectrum -----------------------screenheight Dec 27 13:38:38 netransportnew volumio[1272]: info: PeppySpectrum -----------------------spectrumsize30 Dec 27 13:38:38 netransportnew volumio[1272]: error: PeppySpectrum ---Error writing config null Dec 27 13:38:38 netransportnew sudo[6020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Dec 27 13:38:38 netransportnew sudo[6020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:38 netransportnew systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Dec 27 13:38:38 netransportnew volumio[1272]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 13:38:38 netransportnew volumio[1272]: info: CoreCommandRouter::Close All Modals sent Dec 27 13:38:38 netransportnew systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 27 13:38:38 netransportnew systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Dec 27 13:38:38 netransportnew systemd[1]: peppyspectrum.service: Consumed 5.984s CPU time. Dec 27 13:38:38 netransportnew systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 27 13:38:38 netransportnew sudo[6020]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:38 netransportnew volumio[1272]: info: peppyspectrum Daemon Started Dec 27 13:38:44 netransportnew go-librespot[4433]: time="2025-12-27T13:38:44+01:00" level=trace msg="sent dealer ping" Dec 27 13:38:44 netransportnew go-librespot[4433]: time="2025-12-27T13:38:44+01:00" level=trace msg="received dealer pong" Dec 27 13:38:45 netransportnew startpeppyspectrum.sh[6039]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 13:38:54 netransportnew volumio[1272]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] Dec 27 13:38:54 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 Dec 27 13:38:54 netransportnew volumio[1272]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 27 13:38:54 netransportnew volumio[1272]: info: PeppySpectrum -----------------------spectrums.6 Dec 27 13:38:54 netransportnew volumio[1272]: info: PeppySpectrum -----------------------$basefolder Dec 27 13:38:54 netransportnew volumio[1272]: info: PeppySpectrum -----------------------screensize1280x400 Dec 27 13:38:54 netransportnew volumio[1272]: info: PeppySpectrum -----------------------screenwidth Dec 27 13:38:54 netransportnew volumio[1272]: info: PeppySpectrum -----------------------screenheight Dec 27 13:38:54 netransportnew volumio[1272]: info: PeppySpectrum -----------------------spectrumsize30 Dec 27 13:38:54 netransportnew volumio[1272]: error: PeppySpectrum ---Error writing config null Dec 27 13:38:54 netransportnew sudo[6068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service Dec 27 13:38:54 netransportnew sudo[6068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:38:54 netransportnew systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Dec 27 13:38:54 netransportnew volumio[1272]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 13:38:54 netransportnew volumio[1272]: info: CoreCommandRouter::Close All Modals sent Dec 27 13:38:54 netransportnew systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 27 13:38:54 netransportnew systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Dec 27 13:38:54 netransportnew systemd[1]: peppyspectrum.service: Consumed 3.930s CPU time. Dec 27 13:38:54 netransportnew systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Dec 27 13:38:54 netransportnew sudo[6068]: pam_unix(sudo:session): session closed for user root Dec 27 13:38:54 netransportnew volumio[1272]: info: peppyspectrum Daemon Started Dec 27 13:39:00 netransportnew startpeppyspectrum.sh[6074]: libpng warning: iCCP: known incorrect sRGB profile Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=trace msg="emitting websocket event: not_playing" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=debug msg="loading track (paused: true, position: 1ms)" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:39:08 netransportnew volumio[1272]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:search:bilderbuch","uri":"spotify:track:0xCXcq4y44uHJtJ7zZDF3d","play_origin":"search"}} Dec 27 13:39:08 netransportnew volumio[1272]: error: Failed to decode event: not_playing Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=trace msg="emitting websocket event: will_play" Dec 27 13:39:08 netransportnew volumio[1272]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:search:bilderbuch","uri":"spotify:track:0xCXcq4y44uHJtJ7zZDF3d","play_origin":"search"}} Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=debug msg="selected format OGG_VORBIS_320 (6d7c155c480b0e1be576dde01b35c4a847fccdcb)" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=debug msg="requested aes key for file 6d7c155c480b0e1be576dde01b35c4a847fccdcb, gid: 0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=debug msg="fetched first chunk of 19, total size is 9805848 bytes" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=info msg="loaded track \"Bungalow\" (paused: true, position: 1ms, duration: 222680ms, prefetched: false)" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=trace msg="emitting websocket event: metadata" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=trace msg="emitting websocket event: stopped" Dec 27 13:39:08 netransportnew volumio[1272]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0xCXcq4y44uHJtJ7zZDF3d","name":"Bungalow","artist_names":["Bilderbuch"],"album_name":"Magic Life","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02bf9749fc534464d100af39d8","position":1,"duration":222680,"release_date":"year:2017 month:2 day:17","track_number":5,"disc_number":1}} Dec 27 13:39:08 netransportnew volumio[1272]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"search"}} Dec 27 13:39:08 netransportnew volumio[1272]: SPOTIFY: PUSH STATE SPOTIFY Dec 27 13:39:08 netransportnew volumio[1272]: SPOTIFY: {"status":"stop","service":"spop","title":"Bungalow","artist":"Bilderbuch","album":"Magic Life","albumart":"https://i.scdn.co/image/ab67616d00001e02bf9749fc534464d100af39d8","uri":"spotify:track:0xCXcq4y44uHJtJ7zZDF3d","trackType":"spotify","seek":1,"duration":222,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::servicePushState Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreStateMachine::pushState Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:39:08 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:39:08 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:39:08 netransportnew volumio[1272]: info: CorePlayQueue::getTrack 0 Dec 27 13:39:08 netransportnew volumio[1272]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Bungalow","artist":"Bilderbuch","album":"Magic Life","albumart":"https://i.scdn.co/image/ab67616d00001e02bf9749fc534464d100af39d8","uri":"spotify:track:0xCXcq4y44uHJtJ7zZDF3d","trackType":"spotify","seek":1,"duration":222,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 27 13:39:08 netransportnew volumio[1272]: verbose: CURRENT POSITION 0 Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreStateMachine::syncState stateService stop Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreStateMachine::pushState Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:39:08 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:39:08 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:39:08 netransportnew volumio[1272]: info: No code Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreStateMachine::pushState Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:39:08 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:39:08 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:0xCXcq4y44uHJtJ7zZDF3d" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 27 13:39:08 netransportnew go-librespot[4433]: time="2025-12-27T13:39:08+01:00" level=trace msg="emitting websocket event: paused" Dec 27 13:39:08 netransportnew volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Dec 27 13:39:08 netransportnew volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Dec 27 13:39:08 netransportnew volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Dec 27 13:39:08 netransportnew sudo[6100]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 27 13:39:08 netransportnew sudo[6100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:39:08 netransportnew volumio[1272]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:search:bilderbuch","uri":"spotify:track:0xCXcq4y44uHJtJ7zZDF3d","play_origin":"search"}} Dec 27 13:39:08 netransportnew volumio[1272]: SPOTIFY: PUSH STATE SPOTIFY Dec 27 13:39:08 netransportnew volumio[1272]: SPOTIFY: {"status":"pause","service":"spop","title":"Bungalow","artist":"Bilderbuch","album":"Magic Life","albumart":"https://i.scdn.co/image/ab67616d00001e02bf9749fc534464d100af39d8","uri":"spotify:track:0xCXcq4y44uHJtJ7zZDF3d","trackType":"spotify","seek":1,"duration":222,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::servicePushState Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreStateMachine::pushState Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::volumioPushState Dec 27 13:39:08 netransportnew sudo[6102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 27 13:39:08 netransportnew sudo[6102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:39:08 netransportnew sudo[6105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 27 13:39:08 netransportnew sudo[6105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:39:08 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output update for this device Dec 27 13:39:08 netransportnew volumio[1272]: info: MRS: Pushing multiroomSync output Dec 27 13:39:08 netransportnew volumio[1272]: info: CoreCommandRouter::volumioGetState Dec 27 13:39:08 netransportnew systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Dec 27 13:39:08 netransportnew volumio[1272]: info: PeppySpectrum ---peppyspectrum status pause Dec 27 13:39:08 netransportnew volumio[1272]: info: touch_display: Setting screensaver timeout to 60 seconds. Dec 27 13:39:08 netransportnew sudo[6111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 27 13:39:08 netransportnew sudo[6111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:39:08 netransportnew systemd[1]: peppyspectrum.service: Deactivated successfully. Dec 27 13:39:08 netransportnew systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Dec 27 13:39:08 netransportnew systemd[1]: peppyspectrum.service: Consumed 3.706s CPU time. Dec 27 13:39:08 netransportnew sudo[6100]: pam_unix(sudo:session): session closed for user root Dec 27 13:39:08 netransportnew volumio[1272]: info: peppyspectrum Daemon Stop Dec 27 13:39:08 netransportnew sudo[6111]: pam_unix(sudo:session): session closed for user root Dec 27 13:39:08 netransportnew volumio[1272]: info: peppyspectrum Daemon Stop Dec 27 13:39:08 netransportnew sudo[6105]: pam_unix(sudo:session): session closed for user root Dec 27 13:39:08 netransportnew sudo[6102]: pam_unix(sudo:session): session closed for user root Dec 27 13:39:08 netransportnew volumio[1272]: info: peppyspectrum Daemon Stop Dec 27 13:39:08 netransportnew volumio[1272]: info: peppyspectrum Daemon Stop Dec 27 13:39:11 netransportnew volumio[1272]: info: CALLMETHOD: user_interface peppyspectrum updatelist Dec 27 13:39:11 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist Dec 27 13:39:11 netransportnew volumio[1272]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' Dec 27 13:39:12 netransportnew volumio[1272]: --2025-12-27 13:39:11-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt Dec 27 13:39:12 netransportnew volumio[1272]: Resolving github.com (github.com)... 140.82.121.3 Dec 27 13:39:12 netransportnew volumio[1272]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 27 13:39:12 netransportnew volumio[1272]: HTTP request sent, awaiting response... 302 Found Dec 27 13:39:12 netransportnew volumio[1272]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] Dec 27 13:39:12 netransportnew volumio[1272]: --2025-12-27 13:39:12-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt Dec 27 13:39:12 netransportnew volumio[1272]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.109.133, 185.199.110.133, 185.199.111.133, ... Dec 27 13:39:12 netransportnew volumio[1272]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.109.133|:443... connected. Dec 27 13:39:12 netransportnew volumio[1272]: HTTP request sent, awaiting response... 200 OK Dec 27 13:39:12 netransportnew volumio[1272]: Length: 471 [text/plain] Dec 27 13:39:12 netransportnew volumio[1272]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ Dec 27 13:39:12 netransportnew volumio[1272]: 0K 100% 11.0M=0s Dec 27 13:39:12 netransportnew volumio[1272]: 2025-12-27 13:39:12 (11.0 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] Dec 27 13:39:12 netransportnew volumio[1272]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 27 13:39:12 netransportnew volumio[1272]: info: CoreCommandRouter::Close All Modals sent Dec 27 13:39:14 netransportnew go-librespot[4433]: time="2025-12-27T13:39:14+01:00" level=trace msg="received accesspoint ping" Dec 27 13:39:14 netransportnew go-librespot[4433]: time="2025-12-27T13:39:14+01:00" level=trace msg="received accesspoint pong ack" Dec 27 13:39:14 netransportnew volumio[1272]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Dec 27 13:39:14 netransportnew volumio[1272]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Dec 27 13:39:14 netransportnew go-librespot[4433]: time="2025-12-27T13:39:14+01:00" level=trace msg="sent dealer ping" Dec 27 13:39:14 netransportnew go-librespot[4433]: time="2025-12-27T13:39:14+01:00" level=trace msg="received dealer pong" Dec 27 13:39:15 netransportnew volumio[1272]: --2025-12-27 13:39:14-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Dec 27 13:39:15 netransportnew volumio[1272]: Resolving github.com (github.com)... 140.82.121.3 Dec 27 13:39:15 netransportnew volumio[1272]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 27 13:39:15 netransportnew volumio[1272]: HTTP request sent, awaiting response... 404 Not Found Dec 27 13:39:15 netransportnew volumio[1272]: 2025-12-27 13:39:14 ERROR 404: Not Found. Dec 27 13:39:15 netransportnew volumio[1272]: --2025-12-27 13:39:14-- http://a/ Dec 27 13:39:15 netransportnew volumio[1272]: Resolving a (a)... failed: Device or resource busy. Dec 27 13:39:15 netransportnew volumio[1272]: wget: unable to resolve host address ‘a’ Dec 27 13:39:15 netransportnew volumio[1272]: --2025-12-27 13:39:14-- http://packet.zip/ Dec 27 13:39:15 netransportnew volumio[1272]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 27 13:39:15 netransportnew volumio[1272]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 27 13:39:15 netransportnew volumio[1272]: HTTP request sent, awaiting response... 302 Found Dec 27 13:39:15 netransportnew volumio[1272]: Location: http://www.packet.zip/ [following] Dec 27 13:39:15 netransportnew volumio[1272]: --2025-12-27 13:39:15-- http://www.packet.zip/ Dec 27 13:39:15 netransportnew volumio[1272]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 27 13:39:15 netransportnew volumio[1272]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 27 13:39:15 netransportnew volumio[1272]: HTTP request sent, awaiting response... 403 Forbidden Dec 27 13:39:15 netransportnew volumio[1272]: 2025-12-27 13:39:15 ERROR 403: Forbidden. Dec 27 13:39:15 netransportnew volumio[1272]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Dec 27 13:39:15 netransportnew volumio[1272]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 27 13:39:15 netransportnew volumio[1272]: /bin/rm: cannot remove 'a': No such file or directory Dec 27 13:39:15 netransportnew volumio[1272]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 27 13:39:15 netransportnew volumio[1272]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 13:39:15 netransportnew volumio[1272]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 27 13:39:15 netransportnew volumio[1272]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 27 13:39:15 netransportnew volumio[1272]: /bin/rm: cannot remove 'a': No such file or directory Dec 27 13:39:15 netransportnew volumio[1272]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 27 13:39:15 netransportnew volumio[1272]: at checkExecSyncError (node:child_process:887:11) Dec 27 13:39:15 netransportnew volumio[1272]: at execSync (node:child_process:959:15) Dec 27 13:39:15 netransportnew volumio[1272]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Dec 27 13:39:15 netransportnew volumio[1272]: at new Promise () Dec 27 13:39:15 netransportnew volumio[1272]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Dec 27 13:39:15 netransportnew volumio[1272]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 27 13:39:15 netransportnew volumio[1272]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 27 13:39:15 netransportnew volumio[1272]: at Socket.emit (node:events:514:28) Dec 27 13:39:15 netransportnew volumio[1272]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 27 13:39:15 netransportnew volumio[1272]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 27 13:39:15 netransportnew volumio[1272]: status: 1, Dec 27 13:39:15 netransportnew volumio[1272]: signal: null, Dec 27 13:39:15 netransportnew volumio[1272]: output: [ Dec 27 13:39:15 netransportnew volumio[1272]: null, Dec 27 13:39:15 netransportnew volumio[1272]: , Dec 27 13:39:15 netransportnew volumio[1272]: Dec 27 13:39:15 netransportnew volumio[1272]: ], Dec 27 13:39:15 netransportnew volumio[1272]: pid: 6134, Dec 27 13:39:15 netransportnew volumio[1272]: stdout: , Dec 27 13:39:15 netransportnew volumio[1272]: stderr: Dec 27 13:39:15 netransportnew volumio[1272]: } Dec 27 13:39:15 netransportnew volumio[1272]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 13:39:16 netransportnew sudo[6150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 13:38' Dec 27 13:39:16 netransportnew sudo[6150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"