Mar 28 05:10:01 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 05:10:01 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 05:10:06 volumio nmbd[1027]: [2026/03/28 05:10:06.310158, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 28 05:10:06 volumio nmbd[1027]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.225 for name WORKGROUP<1d>. Mar 28 05:10:06 volumio nmbd[1027]: This response was from IP 192.168.2.229, reporting an IP address of 192.168.2.229. Mar 28 05:10:07 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 05:10:07 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 05:10:07 volumio go-librespot[7711]: time="2026-03-28T05:10:07Z" level=trace msg="received accesspoint ping" Mar 28 05:10:07 volumio go-librespot[7711]: time="2026-03-28T05:10:07Z" level=trace msg="received accesspoint pong ack" Mar 28 05:10:07 volumio go-librespot[7711]: time="2026-03-28T05:10:07Z" level=trace msg="sent dealer ping" Mar 28 05:10:07 volumio go-librespot[7711]: time="2026-03-28T05:10:07Z" level=trace msg="received dealer pong" Mar 28 05:10:12 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 28 05:10:12 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 28 05:10:12 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 28 05:10:13 volumio volumio[1044]: info: [1774674613940] [80s80s] Pushing the next song state Fugees - Ready or Not and getting next track. Mar 28 05:10:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Mar 28 05:10:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:13 volumio volumio[1044]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/cd/5d/75/cd5d754c-347f-2ad6-f033-da8d768c4ab9/source/600x600bb.jpg","name":"Fugees - Ready or Not","title":"Ready or Not","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"217","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 28 05:10:13 volumio volumio[1044]: verbose: CURRENT POSITION 0 Mar 28 05:10:13 volumio volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 28 05:10:13 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 28 05:10:13 volumio volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 28 05:10:13 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:13 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:13 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:13 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:13 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:13 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:13 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:13 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:13.944Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PLAYING positionMs=0 volume=0 Mar 28 05:10:13 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:13.944Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PLAYING positionMs=0 volume=0 Mar 28 05:10:13 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:13.944Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:13 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:13.944Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:13 volumio volumio[1044]: info: [1774674613948] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=141&count=1 Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- → Wakeup triggered Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- → Wakeup triggered Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- → Wakeup triggered Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- → Wakeup triggered Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- → Wakeup triggered Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- → Wakeup triggered Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- → Wakeup triggered Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- → Wakeup triggered Mar 28 05:10:13 volumio volumio[1044]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- → Wakeup triggered Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- → Wakeup triggered Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 28 05:10:14 volumio volumio[1044]: info: Display-configuration --- wakeupScreen: DPMS - screen on Mar 28 05:10:14 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:14 volumio volumio[1044]: info: CorePlayQueue::getTrack 1 Mar 28 05:10:14 volumio volumio[1044]: info: [1774674614299] [80s80s] received new event containing 1 songs. Mar 28 05:10:14 volumio volumio[1044]: info: [1774674614299] [80s80s] PlayNextTrack API delay: 30 Mar 28 05:10:14 volumio volumio[1044]: info: [1774674614302] [80s80s] Setting timer to: 217000 milliseconds. Mar 28 05:10:14 volumio volumio[1044]: info: Received Get System Version Mar 28 05:10:14 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 05:10:21 volumio volumio[1044]: info: CoreCommandRouter::volumioPause Mar 28 05:10:21 volumio volumio[1044]: info: CoreStateMachine::pause Mar 28 05:10:21 volumio volumio[1044]: info: CoreStateMachine::stPlaybackTimer Mar 28 05:10:21 volumio volumio[1044]: info: CoreStateMachine::servicePause Mar 28 05:10:21 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:21 volumio volumio[1044]: info: CoreCommandRouter::servicePause Mar 28 05:10:21 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand pause Mar 28 05:10:21 volumio volumio[1044]: info: Mar 28 05:10:21 volumio volumio[1044]: ---------------------------- MPD announces state update: player Mar 28 05:10:21 volumio volumio[1044]: info: sendMpdCommand pause took 1 milliseconds Mar 28 05:10:21 volumio volumio[1044]: info: ControllerMpd::getState Mar 28 05:10:21 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 28 05:10:21 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:21 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Mar 28 05:10:21 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:21 volumio volumio[1044]: verbose: STATE SERVICE {"status":"pause","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/cd/5d/75/cd5d754c-347f-2ad6-f033-da8d768c4ab9/source/600x600bb.jpg","name":"Fugees - Ready or Not","title":"Ready or Not","artist":"90s90s Hits","album":"","streaming":true,"disableUiControls":true,"duration":"217","seek":7700,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 28 05:10:21 volumio volumio[1044]: verbose: CURRENT POSITION 0 Mar 28 05:10:21 volumio volumio[1044]: info: CoreStateMachine::syncState stateService pause Mar 28 05:10:21 volumio volumio[1044]: info: CoreStateMachine::syncState currentStatus pause Mar 28 05:10:21 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:21 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:21 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 05:10:21 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:21 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:21 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:21 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:21 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:21 volumio volumio[1044]: info: CoreStateMachine::stPlaybackTimer Mar 28 05:10:21 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:21.722Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7700 volume=0 Mar 28 05:10:21 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:21.722Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:21 volumio volumio[1044]: info: sendMpdCommand status took 5 milliseconds Mar 28 05:10:21 volumio volumio[1044]: verbose: ControllerMpd::parseState Mar 28 05:10:21 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 28 05:10:21 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:21 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:21 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:21 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:21 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:21 volumio volumio[1044]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 28 05:10:21 volumio volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 28 05:10:21 volumio volumio[1044]: info: ControllerMpd::pushState Mar 28 05:10:21 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Mar 28 05:10:21 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:21 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 28 05:10:21 volumio volumio[1044]: info: ------------------------------ 13ms Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 05:10:25 volumio volumio[1044]: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:25 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 28 05:10:25 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 28 05:10:37 volumio volumio[1044]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Mar 28 05:10:37 volumio sudo[12055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 05:10:37 volumio sudo[12055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:37 volumio sudo[12055]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:37 volumio sudo[12057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 05:10:37 volumio sudo[12057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:37 volumio volumio[1044]: info: MPD Permissions set Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:37 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 28 05:10:37 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:37 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 05:10:37 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 05:10:37 volumio systemd[1]: mpd.service: Consumed 1min 20.838s CPU time. Mar 28 05:10:37 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 05:10:37 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 05:10:37 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 05:10:37 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 05:10:37 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 05:10:37 volumio sudo[12072]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 28 05:10:37 volumio sudo[12072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 05:10:37 volumio sudo[12072]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:37 volumio go-librespot[7711]: time="2026-03-28T05:10:37Z" level=trace msg="sent dealer ping" Mar 28 05:10:37 volumio go-librespot[7711]: time="2026-03-28T05:10:37Z" level=trace msg="received dealer pong" Mar 28 05:10:37 volumio mpd[12074]: 2026-03-28T05:10:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 05:10:37 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 28 05:10:37 volumio sudo[12057]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:37 volumio volumio[1044]: error: updateQueue error: null Mar 28 05:10:49 volumio volumio[1044]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:49 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:49 volumio volumio[1044]: info: Restoring Previous Volume level: 100 false false Mar 28 05:10:49 volumio volumio[1044]: info: Output device has changed, restarting MPD Mar 28 05:10:49 volumio volumio[1044]: info: Output device has changed, restarting Shairport Sync Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:49 volumio sudo[12105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 05:10:49 volumio sudo[12105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio sudo[12105]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:49 volumio sudo[12107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 05:10:49 volumio sudo[12107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio volumio[1044]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 05:10:49 volumio volumio[1044]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 28 05:10:49 volumio volumio[1044]: info: QobuzConnect: setDeactiveState invoked Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:49 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:49 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 05:10:49 volumio vtcs[7229]: [2026-03-28 05:10:49.030] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 28 05:10:49 volumio vtcs[7229]: [2026-03-28 05:10:49.031] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 28 05:10:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 05:10:49 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 05:10:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 05:10:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 05:10:49 volumio volumio[1044]: info: Volume configurations have been set Mar 28 05:10:49 volumio volumio[1044]: info: QobuzConnect: setDeactiveState invoked Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:49 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:49 volumio sudo[12118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:49 volumio sudo[12118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio sudo[12120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:49 volumio sudo[12120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 28 05:10:49 volumio volumio[1044]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 28 05:10:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 28 05:10:49 volumio volumio[1044]: info: Disabling external Volume Control Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 05:10:49 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:49 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 05:10:49 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:49.058Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=100 Mar 28 05:10:49 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:49.058Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:49 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:49 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:49 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:49 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 28 05:10:49 volumio systemd[1]: vtcs.service: Killing process 7235 (vtcs) with signal SIGKILL. Mar 28 05:10:49 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 28 05:10:49 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 28 05:10:49 volumio sudo[12129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:49 volumio sudo[12129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio sudo[12120]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:49 volumio sudo[12118]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:49 volumio sudo[12131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:49 volumio sudo[12131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio sudo[12129]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:49 volumio sudo[12127]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 28 05:10:49 volumio sudo[12127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 05:10:49 volumio sudo[12127]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:49 volumio sudo[12143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 28 05:10:49 volumio sudo[12143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:49 volumio sudo[12143]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:49 volumio sudo[12131]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:49 volumio sudo[12151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 28 05:10:49 volumio sudo[12152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 28 05:10:49 volumio sudo[12152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio sudo[12151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 28 05:10:49 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:49 volumio sudo[12151]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:49 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:49 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:49 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:49 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:49 volumio qobuz-connect[7179]: 20260328 05:10:49.124 [7179.7179] INFO SampleApp: Stopping Local configuration server Mar 28 05:10:49 volumio volumio[1044]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:49 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:49 volumio volumio[1044]: info: MPD Permissions set Mar 28 05:10:49 volumio sudo[12156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 28 05:10:49 volumio sudo[12156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 05:10:49 volumio volumio[1044]: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:49 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 28 05:10:49 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 28 05:10:49 volumio volumio[1044]: info: Starting Shairport Sync Mar 28 05:10:49 volumio sudo[12173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 05:10:49 volumio sudo[12173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 28 05:10:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 28 05:10:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 05:10:49 volumio systemd[1]: shairport-sync.service: Consumed 6.098s CPU time. Mar 28 05:10:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 05:10:49 volumio sudo[12173]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:49 volumio volumio[1044]: info: Shairport-Sync Started Mar 28 05:10:49 volumio volumio[1044]: (node:1044) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 28 05:10:49 volumio volumio[1044]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 28 05:10:49 volumio volumio[1044]: (node:1044) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 28 05:10:49 volumio volumio[1044]: (node:1044) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 28 05:10:49 volumio volumio[1044]: (node:1044) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 28 05:10:49 volumio volumio[1044]: (node:1044) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 28 05:10:49 volumio mpd[12145]: 2026-03-28T05:10:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 05:10:49 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 28 05:10:49 volumio sudo[12107]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:49 volumio volumio[1044]: error: updateQueue error: null Mar 28 05:10:50 volumio qobuz-connect[7179]: 20260328 05:10:50.423 [7179.7179] INFO SampleApp: shat down connection on UNIX socket Mar 28 05:10:50 volumio volumio[1044]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 28 05:10:50 volumio volumio[1044]: info: QobuzConnect: setDeactiveState invoked Mar 28 05:10:50 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:50 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:50 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 28 05:10:50 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 28 05:10:50 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 28 05:10:50 volumio sudo[12152]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:50 volumio sudo[12156]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:50 volumio volumio[1044]: info: Executing endpoint qc_getconfig Mar 28 05:10:50 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 28 05:10:50 volumio qobuz-connect[12208]: 20260328 05:10:50.471 [12208.12208] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 28 05:10:50 volumio qobuz-connect[12208]: 20260328 05:10:50.472 [12208.12208] INFO VolumeManager: [0x55f5477bb470]: Setting new playback volume: 75 Mar 28 05:10:50 volumio qobuz-connect[12208]: 20260328 05:10:50.472 [12208.12208] INFO VolumeManager: [0x55f5477bb470]: Setting new mute state: 0 Mar 28 05:10:50 volumio qobuz-connect[12208]: 20260328 05:10:50.472 [12208.12208] INFO AudioStreamManager: [0x55f5477bafd0]: Setting new audio download buffer size: 1048576 Mar 28 05:10:50 volumio qobuz-connect[12208]: 20260328 05:10:50.472 [12208.12208] INFO QobuzConnect: [0x55f5477bc480]: Client initialized! Mar 28 05:10:50 volumio qobuz-connect[12208]: 20260328 05:10:50.472 [12208.12208] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Mar 28 05:10:50 volumio volumio[1044]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 28 05:10:50 volumio volumio[1044]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 28 05:10:50 volumio qobuz-connect[12208]: 20260328 05:10:50.475 [12208.12208] INFO LocalConfigManager: [0x55f5477baab0]: Starting Local Configuration server Mar 28 05:10:50 volumio qobuz-connect[12208]: 20260328 05:10:50.475 [12208.12208] INFO SampleApp: Starting Local configuration server Mar 28 05:10:50 volumio qobuz-connect[12208]: 20260328 05:10:50.475 [12208.12208] INFO SampleApp: Connected to UNIX socket client 0x55f547791bb0 Mar 28 05:10:50 volumio qobuz-connect[12208]: 20260328 05:10:50.621 [12208.12208] INFO SampleApp: Playback volume changed: 75 Mar 28 05:10:50 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:50 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:52 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:52 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:52 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:52 volumio volumio[1044]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:52 volumio volumio[1044]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:52 volumio sudo[12224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 28 05:10:52 volumio sudo[12224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:52 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:52 volumio volumio[1044]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:52 volumio volumio[1044]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:52 volumio sudo[12236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 28 05:10:52 volumio sudo[12236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:52 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 28 05:10:52 volumio sudo[12224]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:52 volumio sudo[12236]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:52 volumio volumio[1044]: info: Executing endpoint tc_getconfig Mar 28 05:10:52 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 28 05:10:52 volumio vtcs[12238]: STARTING TidalConnect services, version: 1.6.1 Mar 28 05:10:52 volumio vtcs[12238]: STARTED TidalConnect services. Mar 28 05:10:52 volumio volumio[1044]: info: Executing endpoint tc_connect Mar 28 05:10:52 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 28 05:10:52 volumio volumio[1044]: info: Connecting to TidalConnect Mar 28 05:10:52 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Mar 28 05:10:52 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:52 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:52 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:52 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:52 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:52 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:52 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:52 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:52 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received tidalconnect Mar 28 05:10:52 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Mar 28 05:10:52 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:52 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:52 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:52 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:52 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:52 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:52 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:52 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:52 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received tidalconnect Mar 28 05:10:52 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:52.188Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=100 Mar 28 05:10:52 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:52.188Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:52 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:52.188Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=100 Mar 28 05:10:52 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:52.188Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:52 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:52 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:52 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:52 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:52 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:52 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:52 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:52 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:52 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:52 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: VolumeController::SetAlsaVolume100 Mar 28 05:10:53 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:53 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:53 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:53 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:53 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:53 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:53.511Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=100 Mar 28 05:10:53 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:53.511Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:53 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 28 05:10:53 volumio volumio[1044]: info: Preparing to save Alsa Options, stopping services first Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:53 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::volumioPause Mar 28 05:10:53 volumio volumio[1044]: info: CoreStateMachine::pause Mar 28 05:10:53 volumio volumio[1044]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 05:10:53 volumio volumio[1044]: info: Found match in Cards Database: setting mixer for card HDMI Mar 28 05:10:53 volumio volumio[1044]: info: QobuzConnect: setDeactiveState invoked Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:53 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:53 volumio vtcs[12238]: [2026-03-28 05:10:53.812] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 28 05:10:53 volumio vtcs[12238]: [2026-03-28 05:10:53.812] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 28 05:10:53 volumio sudo[12266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:53 volumio sudo[12266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio volumio[1044]: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:53 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 28 05:10:53 volumio volumio[1044]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 28 05:10:53 volumio volumio[1044]: info: Disabling external Volume Control Mar 28 05:10:53 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 28 05:10:53 volumio systemd[1]: vtcs.service: Deactivated successfully. Mar 28 05:10:53 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 05:10:53 volumio volumio[1044]: info: Preparing to generate the ALSA configuration file Mar 28 05:10:53 volumio sudo[12266]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:53 volumio sudo[12278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:53 volumio sudo[12278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 28 05:10:53 volumio volumio[1044]: info: Reading ALSA contributions from plugins. Mar 28 05:10:53 volumio volumio[1044]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:53 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:53 volumio sudo[12285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 28 05:10:53 volumio sudo[12285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio sudo[12278]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:53 volumio sudo[12285]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 05:10:53 volumio sudo[12292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 28 05:10:53 volumio sudo[12292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio volumio[1044]: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:53 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:53 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 28 05:10:53 volumio qobuz-connect[12208]: 20260328 05:10:53.878 [12208.12208] INFO SampleApp: Stopping Local configuration server Mar 28 05:10:53 volumio volumio[1044]: info: VolumeController:: Volume=undefined Mute =false Mar 28 05:10:53 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:53 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:53 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:53 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:53 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:53 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:53.882Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=0 Mar 28 05:10:53 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:53.883Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:53 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:53 volumio volumio[1044]: info: Asound.conf file written Mar 28 05:10:53 volumio sudo[12299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 28 05:10:53 volumio sudo[12299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio sudo[12299]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:53 volumio volumio[1044]: info: Output device has changed, restarting MPD Mar 28 05:10:53 volumio sudo[12305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 05:10:53 volumio sudo[12305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio volumio[1044]: info: Output device has changed, restarting Shairport Sync Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:53 volumio sudo[12305]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:53 volumio sudo[12308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 05:10:53 volumio sudo[12308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio volumio[1044]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 05:10:53 volumio volumio[1044]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:53 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 28 05:10:53 volumio volumio[1044]: info: QobuzConnect: setDeactiveState invoked Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:53 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:53 volumio volumio[1044]: info: Output device has changed, restarting MPD Mar 28 05:10:53 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 05:10:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 05:10:53 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 05:10:53 volumio sudo[12317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:53 volumio sudo[12317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 05:10:53 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 05:10:53 volumio sudo[12319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 05:10:53 volumio sudo[12319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio sudo[12319]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:53 volumio volumio[1044]: info: Output device has changed, restarting Shairport Sync Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:53 volumio sudo[12324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 05:10:53 volumio sudo[12324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio volumio[1044]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 05:10:53 volumio volumio[1044]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:53 volumio volumio[1044]: info: QobuzConnect: setDeactiveState invoked Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:53 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:53 volumio sudo[12333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:53 volumio sudo[12333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 05:10:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 05:10:53 volumio sudo[12336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:53 volumio sudo[12336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio sudo[12339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:53 volumio sudo[12339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:53 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 05:10:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 05:10:53 volumio sudo[12347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 28 05:10:53 volumio sudo[12347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:53 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:53 volumio sudo[12354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 28 05:10:53 volumio sudo[12354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio volumio[1044]: info: MPD Permissions set Mar 28 05:10:54 volumio volumio[1044]: info: MPD Permissions set Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio sudo[12317]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 28 05:10:54 volumio sudo[12333]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio sudo[12336]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio sudo[12339]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 28 05:10:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 05:10:54 volumio sudo[12347]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio sudo[12354]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 05:10:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio sudo[12376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 28 05:10:54 volumio sudo[12376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio sudo[12375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 28 05:10:54 volumio sudo[12375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio volumio[1044]: info: Starting Shairport Sync Mar 28 05:10:54 volumio volumio[1044]: info: Starting Shairport Sync Mar 28 05:10:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 05:10:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 05:10:54 volumio sudo[12385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 05:10:54 volumio sudo[12385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio sudo[12383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 05:10:54 volumio sudo[12383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 28 05:10:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 28 05:10:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 05:10:54 volumio systemd[1]: shairport-sync.service: Consumed 1.527s CPU time. Mar 28 05:10:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 05:10:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 28 05:10:54 volumio sudo[12385]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 28 05:10:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 05:10:54 volumio volumio[1044]: info: Shairport-Sync Started Mar 28 05:10:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 05:10:54 volumio sudo[12383]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio volumio[1044]: info: Shairport-Sync Started Mar 28 05:10:54 volumio sudo[12386]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 28 05:10:54 volumio sudo[12386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 05:10:54 volumio sudo[12386]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio mpd[12392]: 2026-03-28T05:10:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 05:10:54 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 28 05:10:54 volumio sudo[12324]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio sudo[12308]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio volumio[1044]: error: MPD error: The expression evaluated to a falsy value: Mar 28 05:10:54 volumio volumio[1044]: assert.ok(self.idling) Mar 28 05:10:54 volumio volumio[1044]: error: The expression evaluated to a falsy value: Mar 28 05:10:54 volumio volumio[1044]: assert.ok(self.idling) Mar 28 05:10:54 volumio volumio[1044]: error: updateQueue error: null Mar 28 05:10:54 volumio qobuz-connect[12208]: 20260328 05:10:54.486 [12208.12208] INFO SampleApp: shat down connection on UNIX socket Mar 28 05:10:54 volumio volumio[1044]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 28 05:10:54 volumio volumio[1044]: info: QobuzConnect: setDeactiveState invoked Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:54 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 28 05:10:54 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 28 05:10:54 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 28 05:10:54 volumio sudo[12376]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio sudo[12292]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio sudo[12375]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio volumio[1044]: info: Executing endpoint qc_getconfig Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.529 [12410.12410] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 28 05:10:54 volumio volumio[1044]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 28 05:10:54 volumio volumio[1044]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.530 [12410.12410] INFO VolumeManager: [0x55b30474c470]: Setting new playback volume: 75 Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.530 [12410.12410] INFO VolumeManager: [0x55b30474c470]: Setting new mute state: 0 Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.530 [12410.12410] INFO AudioStreamManager: [0x55b30474bfd0]: Setting new audio download buffer size: 1048576 Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.530 [12410.12410] INFO QobuzConnect: [0x55b30474d480]: Client initialized! Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.530 [12410.12410] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.533 [12410.12410] INFO LocalConfigManager: [0x55b30474bab0]: Starting Local Configuration server Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.533 [12410.12410] INFO SampleApp: Starting Local configuration server Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.533 [12410.12410] INFO SampleApp: Connected to UNIX socket client 0x55b304722bb0 Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.679 [12410.12410] INFO SampleApp: Playback volume changed: 75 Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:54 volumio volumio[1044]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 28 05:10:54 volumio volumio[1044]: info: Preparing to save Alsa Options, stopping services first Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::volumioPause Mar 28 05:10:54 volumio volumio[1044]: info: CoreStateMachine::pause Mar 28 05:10:54 volumio volumio[1044]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 05:10:54 volumio volumio[1044]: info: Found match in Cards Database: setting mixer for card HDMI Mar 28 05:10:54 volumio volumio[1044]: info: QobuzConnect: setDeactiveState invoked Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:54 volumio sudo[12428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:54 volumio sudo[12428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio volumio[1044]: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:54 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 28 05:10:54 volumio volumio[1044]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 28 05:10:54 volumio volumio[1044]: info: Disabling external Volume Control Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 05:10:54 volumio sudo[12428]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio volumio[1044]: info: Preparing to generate the ALSA configuration file Mar 28 05:10:54 volumio sudo[12436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:54 volumio sudo[12436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 28 05:10:54 volumio volumio[1044]: info: Reading ALSA contributions from plugins. Mar 28 05:10:54 volumio volumio[1044]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:54 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:54 volumio volumio[1044]: info: VolumeController:: Volume=undefined Mute =false Mar 28 05:10:54 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:54 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:54 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:54 volumio sudo[12436]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:54 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:54.769Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=0 Mar 28 05:10:54 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:54.769Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:54 volumio sudo[12443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 28 05:10:54 volumio sudo[12443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:54 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:54 volumio sudo[12443]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:54 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:54 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:54 volumio sudo[12447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 28 05:10:54 volumio sudo[12447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 28 05:10:54 volumio qobuz-connect[12410]: 20260328 05:10:54.792 [12410.12410] INFO SampleApp: Stopping Local configuration server Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 05:10:54 volumio volumio[1044]: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:54 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 28 05:10:54 volumio volumio[1044]: info: Asound.conf file unchanged, so no further update is needed Mar 28 05:10:54 volumio volumio[1044]: info: Output device has changed, restarting MPD Mar 28 05:10:54 volumio volumio[1044]: info: Output device has changed, restarting Shairport Sync Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:54 volumio sudo[12456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 28 05:10:54 volumio sudo[12456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio sudo[12456]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio sudo[12458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 28 05:10:54 volumio sudo[12458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio volumio[1044]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 28 05:10:54 volumio volumio[1044]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: QobuzConnect: setDeactiveState invoked Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:54 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:54 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 28 05:10:54 volumio sudo[12468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:54 volumio sudo[12468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 28 05:10:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 28 05:10:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 28 05:10:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 28 05:10:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 28 05:10:54 volumio sudo[12473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 28 05:10:54 volumio sudo[12473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: MPD Permissions set Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio sudo[12479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 28 05:10:54 volumio sudo[12479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 28 05:10:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 05:10:54 volumio volumio[1044]: info: Starting Shairport Sync Mar 28 05:10:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 28 05:10:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 28 05:10:54 volumio sudo[12490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 28 05:10:54 volumio sudo[12490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio sudo[12479]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio sudo[12493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 28 05:10:54 volumio sudo[12493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:54 volumio sudo[12468]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio sudo[12491]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 28 05:10:54 volumio sudo[12491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 28 05:10:54 volumio sudo[12491]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio sudo[12473]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 28 05:10:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 28 05:10:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 05:10:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 28 05:10:54 volumio sudo[12490]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:54 volumio volumio[1044]: info: Shairport-Sync Started Mar 28 05:10:55 volumio volumio[1044]: info: TidalConnect service started! Mar 28 05:10:55 volumio volumio[1044]: info: TidalConnect service started! Mar 28 05:10:55 volumio mpd[12498]: 2026-03-28T05:10:55 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 28 05:10:55 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 28 05:10:55 volumio sudo[12458]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:55 volumio volumio[1044]: error: updateQueue error: null Mar 28 05:10:56 volumio qobuz-connect[12410]: 20260328 05:10:56.543 [12410.12410] INFO SampleApp: shat down connection on UNIX socket Mar 28 05:10:56 volumio volumio[1044]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 28 05:10:56 volumio volumio[1044]: info: QobuzConnect: setDeactiveState invoked Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:56 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 28 05:10:56 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 28 05:10:56 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 28 05:10:56 volumio sudo[12493]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:56 volumio sudo[12447]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:56 volumio volumio[1044]: info: Executing endpoint qc_getconfig Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 28 05:10:56 volumio qobuz-connect[12518]: 20260328 05:10:56.582 [12518.12518] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 28 05:10:56 volumio volumio[1044]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 28 05:10:56 volumio volumio[1044]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 28 05:10:56 volumio qobuz-connect[12518]: 20260328 05:10:56.583 [12518.12518] INFO VolumeManager: [0x556997a66470]: Setting new playback volume: 75 Mar 28 05:10:56 volumio qobuz-connect[12518]: 20260328 05:10:56.583 [12518.12518] INFO VolumeManager: [0x556997a66470]: Setting new mute state: 0 Mar 28 05:10:56 volumio qobuz-connect[12518]: 20260328 05:10:56.583 [12518.12518] INFO AudioStreamManager: [0x556997a65fd0]: Setting new audio download buffer size: 1048576 Mar 28 05:10:56 volumio qobuz-connect[12518]: 20260328 05:10:56.583 [12518.12518] INFO QobuzConnect: [0x556997a67480]: Client initialized! Mar 28 05:10:56 volumio qobuz-connect[12518]: 20260328 05:10:56.583 [12518.12518] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Mar 28 05:10:56 volumio qobuz-connect[12518]: 20260328 05:10:56.585 [12518.12518] INFO LocalConfigManager: [0x556997a65ab0]: Starting Local Configuration server Mar 28 05:10:56 volumio qobuz-connect[12518]: 20260328 05:10:56.585 [12518.12518] INFO SampleApp: Starting Local configuration server Mar 28 05:10:56 volumio qobuz-connect[12518]: 20260328 05:10:56.586 [12518.12518] INFO SampleApp: Connected to UNIX socket client 0x556997a3cbb0 Mar 28 05:10:56 volumio qobuz-connect[12518]: 20260328 05:10:56.732 [12518.12518] INFO SampleApp: Playback volume changed: 75 Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:56 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:56 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:56 volumio volumio[1044]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:56 volumio volumio[1044]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:56 volumio sudo[12533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 28 05:10:56 volumio sudo[12533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:56 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 28 05:10:56 volumio sudo[12533]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:56 volumio volumio[1044]: info: Executing endpoint tc_getconfig Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 28 05:10:56 volumio vtcs[12536]: STARTING TidalConnect services, version: 1.6.1 Mar 28 05:10:56 volumio vtcs[12536]: STARTED TidalConnect services. Mar 28 05:10:56 volumio volumio[1044]: info: Executing endpoint tc_connect Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 28 05:10:56 volumio volumio[1044]: info: Connecting to TidalConnect Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Mar 28 05:10:56 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:56 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:56 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:56 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received tidalconnect Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Mar 28 05:10:56 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:56 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:56 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:56 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:56 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:56 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received tidalconnect Mar 28 05:10:56 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:56.951Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=0 Mar 28 05:10:56 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:56.951Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:56 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:56.951Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=0 Mar 28 05:10:56 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:56.951Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:56 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:56 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:56 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:56 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:56 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:56 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:56 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:56 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:56 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:56 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:57 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:57 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:57 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:57 volumio volumio[1044]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:57 volumio volumio[1044]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:57 volumio sudo[12565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 28 05:10:57 volumio sudo[12565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:57 volumio volumio[1044]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:57 volumio volumio[1044]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:57 volumio sudo[12565]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:57 volumio sudo[12572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 28 05:10:57 volumio sudo[12572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:57 volumio sudo[12572]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:57 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:57 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:57 volumio volumio[1044]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:57 volumio volumio[1044]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:57 volumio sudo[12586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 28 05:10:57 volumio sudo[12586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:57 volumio sudo[12586]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:57 volumio volumio[1044]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 28 05:10:57 volumio volumio[1044]: info: CoreStateMachine::getcurrentVolume Mar 28 05:10:57 volumio volumio[1044]: info: CoreCommandRouter::volumioRetrievevolume Mar 28 05:10:57 volumio volumio[1044]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:57 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:57 volumio volumio[1044]: info: VolumeController:: Volume=undefined Mute =false Mar 28 05:10:57 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:57 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:57 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:57 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:57 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:57 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:57 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:57 volumio volumio[1044]: info: CoreStateMachine::updateTrackBlock Mar 28 05:10:57 volumio volumio[1044]: info: CorePlayQueue::getTrackBlock Mar 28 05:10:57 volumio volumio[1044]: info: CoreCommandRouter::volumioRetrievevolume Mar 28 05:10:57 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:57.848Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=0 Mar 28 05:10:57 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:57.848Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:57 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:57 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:57 volumio volumio[1044]: info: VolumeController:: Volume=undefined Mute =false Mar 28 05:10:57 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:57 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:57 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:57 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:57 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:57 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:57 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:57 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:57.862Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=0 Mar 28 05:10:57 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:57.862Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:57 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:57 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:57 volumio volumio[1044]: info: TidalConnect service stoped! Mar 28 05:10:57 volumio volumio[1044]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:57 volumio volumio[1044]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 28 05:10:57 volumio sudo[12602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 28 05:10:57 volumio sudo[12602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 05:10:57 volumio sudo[12602]: pam_unix(sudo:session): session closed for user root Mar 28 05:10:58 volumio volumio[1044]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 28 05:10:58 volumio volumio[1044]: info: CoreStateMachine::getcurrentVolume Mar 28 05:10:58 volumio volumio[1044]: info: CoreCommandRouter::volumioRetrievevolume Mar 28 05:10:58 volumio volumio[1044]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:58 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:58 volumio volumio[1044]: info: VolumeController:: Volume=undefined Mute =false Mar 28 05:10:58 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:58 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:58 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:58 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:58 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:58 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:58 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:58 volumio volumio[1044]: info: CoreStateMachine::updateTrackBlock Mar 28 05:10:58 volumio volumio[1044]: info: CorePlayQueue::getTrackBlock Mar 28 05:10:58 volumio volumio[1044]: info: CoreCommandRouter::volumioRetrievevolume Mar 28 05:10:58 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:58.756Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=0 Mar 28 05:10:58 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:58.756Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:58 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:58 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:58 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:58 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:58 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:58 volumio volumio[1044]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib simple_none.c:1555:(simple_add1) helem (MIXER,'SoftMaster',0,3,0) appears twice or more Mar 28 05:10:58 volumio volumio[1044]: amixer: Mixer sysdefault:0 load error: Invalid argument Mar 28 05:10:58 volumio volumio[1044]: info: VolumeController:: Volume=undefined Mute =false Mar 28 05:10:58 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:10:58 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:58 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:10:58 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:10:58 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:10:58 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:10:58 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:10:58 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:58.771Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_PAUSED positionMs=7950 volume=0 Mar 28 05:10:58 volumio volumio5-onboarding[1408]: time=2026-03-28T05:10:58.771Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:10:58 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:58 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:58 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:58 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:58 volumio volumio[1044]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Mar 28 05:10:59 volumio volumio[1044]: info: TidalConnect service started! Mar 28 05:11:00 volumio volumio[1044]: info: TidalConnect service started! Mar 28 05:11:00 volumio volumio[1044]: info: TidalConnect service started! Mar 28 05:11:00 volumio volumio[1044]: info: TidalConnect service started! Mar 28 05:11:00 volumio volumio[1044]: info: TidalConnect service started! Mar 28 05:11:04 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Mar 28 05:11:04 volumio volumio[1044]: info: [soundcloud] browseUri: soundcloud Mar 28 05:11:04 volumio volumio[1044]: info: Preload queue cleared Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2086809642 Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2242464131 Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2096380413 Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2258818436 Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2259068951 Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2140467141 Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=829808716 Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=252949630 Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2110454328 Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2177429151 Mar 28 05:11:04 volumio volumio[1044]: info: Preloading song: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2261531900 Mar 28 05:11:04 volumio volumio[1044]: info: Preload queue cleared Mar 28 05:11:04 volumio volumio[1044]: info: Preload queue cleared Mar 28 05:11:04 volumio volumio[1044]: info: Preload queue cleared Mar 28 05:11:04 volumio volumio[1044]: info: Preload queue cleared Mar 28 05:11:04 volumio volumio[1044]: info: Preload queue cleared Mar 28 05:11:04 volumio volumio[1044]: info: Preload queue cleared Mar 28 05:11:06 volumio volumio[1044]: info: Preload queue cleared Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::ClearQueue Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::stop Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::updateTrackBlock Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrackBlock Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::stPlaybackTimer Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:11:06 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:11:06 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::serviceStop Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::serviceStop Mar 28 05:11:06 volumio volumio[1044]: info: ControllerMpd::stop Mar 28 05:11:06 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::clearPlayQueue Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::saveQueue Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioPushQueue Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::addQueueItems Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::addQueueItems Mar 28 05:11:06 volumio volumio[1044]: info: Preload queue cleared Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2086809642 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2086809642 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2086809642 Mar 28 05:11:06 volumio volumio5-onboarding[1408]: time=2026-03-28T05:11:06.049Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_STOPPED positionMs=0 volume=0 Mar 28 05:11:06 volumio volumio5-onboarding[1408]: time=2026-03-28T05:11:06.049Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id=http://streams.90s90s.de/pop/mp3-192/volumio title="Fugees - Ready or Not" Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2086809642 Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioPushQueue Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::saveQueue Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::updateTrackBlock Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrackBlock Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioPlay Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::play index 0 Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::addQueueItems Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::addQueueItems Mar 28 05:11:06 volumio volumio[1044]: info: Preload queue cleared Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2242464131 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2242464131 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2242464131 Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2096380413 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2096380413 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2096380413 Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2258818436 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2258818436 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2258818436 Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2259068951 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2259068951 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2259068951 Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2140467141 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2140467141 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2140467141 Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=829808716 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=829808716 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=829808716 Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=252949630 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=252949630 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=252949630 Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2110454328 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2110454328 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2110454328 Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2177429151 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2177429151 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2177429151 Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2261531900 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2261531900 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@inSection=1@title=Top-Featured%20Tracks/track@trackId=2261531900 Mar 28 05:11:06 volumio volumio[1044]: info: Adding Item to queue: soundcloud/tracks@topFeatured=1@title=Top-Featured%20Tracks@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 Mar 28 05:11:06 volumio volumio[1044]: info: Exploding uri soundcloud/tracks@topFeatured=1@title=Top-Featured%20Tracks@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 in service soundcloud Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] explodeUri: soundcloud/tracks@topFeatured=1@title=Top-Featured%20Tracks@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::stop Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::play index undefined Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::startPlaybackTimer Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2086809642 Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2242464131 Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2096380413 Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2258818436 Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2259068951 Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2140467141 Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=829808716 Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=252949630 Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2110454328 Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2177429151 Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2261531900 Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioPushQueue Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::saveQueue Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::updateTrackBlock Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrackBlock Mar 28 05:11:06 volumio volumio[1044]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 28 05:11:06 volumio volumio[1044]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 28 05:11:06 volumio volumio[1044]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 28 05:11:06 volumio volumio[1044]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 28 05:11:06 volumio volumio[1044]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 28 05:11:06 volumio volumio[1044]: info: sendMpdCommand stop took 27 milliseconds Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Mar 28 05:11:06 volumio volumio[1044]: info: CoreStateMachine::pushState Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 28 05:11:06 volumio volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 28 05:11:06 volumio volumio[1044]: info: MRS: Pushing multiroomSync output Mar 28 05:11:06 volumio volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:11:06 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Mar 28 05:11:06 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received 80s80s Mar 28 05:11:06 volumio volumio5-onboarding[1408]: time=2026-03-28T05:11:06.075Z level=INFO msg="emitting player state changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" state=STATUS_STOPPED positionMs=0 volume=0 Mar 28 05:11:06 volumio volumio5-onboarding[1408]: time=2026-03-28T05:11:06.075Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.230:41342 @ 0xc000325bc0" id="soundcloud/track@trackId=2086809642" title="Bottle Rockets (feat. Hootie & The Blowfish)" Mar 28 05:11:06 volumio volumio[1044]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 28 05:11:06 volumio volumio[1044]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 28 05:11:06 volumio volumio[1044]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 28 05:11:06 volumio volumio[1044]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 28 05:11:06 volumio volumio[1044]: info: Display-configuration --- Volumio status=stop timeout=120 noifplay=true screensavertype=dpms Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] [mpv] mpv process spawned Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] Going to start mpv for playback Mar 28 05:11:06 volumio volumio[1044]: info: [soundcloud] [mpv] (PID: 12642) /bin/sh: 1: mpv: not found Mar 28 05:11:06 volumio volumio[1044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 05:11:06 volumio volumio[1044]: TypeError: Cannot read properties of null (reading 'reset') Mar 28 05:11:06 volumio volumio[1044]: at /data/plugins/music_service/soundcloud/node_modules/volumio-ext-players/dist/mpv/MPVService.js:170:48 Mar 28 05:11:06 volumio volumio[1044]: at ChildProcess. (/data/plugins/music_service/soundcloud/node_modules/volumio-ext-players/dist/mpv/MPVService.js:184:19) Mar 28 05:11:06 volumio volumio[1044]: at ChildProcess.emit (node:events:514:28) Mar 28 05:11:06 volumio volumio[1044]: at maybeClose (node:internal/child_process:1105:16) Mar 28 05:11:06 volumio volumio[1044]: at Socket. (node:internal/child_process:457:11) Mar 28 05:11:06 volumio volumio[1044]: at Socket.emit (node:events:514:28) Mar 28 05:11:06 volumio volumio[1044]: at Pipe. (node:net:337:12) Mar 28 05:11:06 volumio volumio[1044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 05:11:06 volumio sudo[12658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 05:10' Mar 28 05:11:06 volumio sudo[12658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"