-- Logs begin at Sun 2026-05-31 10:40:31 CEST, end at Sun 2026-05-31 16:51:21 CEST. -- May 31 16:50:02 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:02 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705357. May 31 16:50:02 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:02 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:02 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:02 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:07 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:07 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705358. May 31 16:50:07 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:07 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:07 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:07 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:12 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:12 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705359. May 31 16:50:12 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:12 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:13 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:13 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:18 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:18 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705360. May 31 16:50:18 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:18 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:18 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:18 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:23 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:23 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705361. May 31 16:50:23 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:23 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:23 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:23 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:26 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:26.430+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.102:50776 May 31 16:50:26 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:26.473+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.102:50776 @ 0x2f388d0" latency=-234.394459ms platform=PLATFORM_ANDROID version=5.260413.0 May 31 16:50:26 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:26.480+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.102:50776 @ 0x2f388d0" latency=-228.946504ms timeout=10s May 31 16:50:26 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:26.480+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" May 31 16:50:26 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:26 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:26 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:26 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:26 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:26 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:26 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:26.576+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" name=MyVolumioZero May 31 16:50:26 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:26.586+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" language=en May 31 16:50:26 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 31 16:50:26 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:26.597+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" timezone=Europe/Paris May 31 16:50:26 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:26.600+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" available=false connected=false macAddress= ip4Address= ip6Address= May 31 16:50:26 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:26.613+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" available=true connected=true macAddress=b8:27:eb:d4:fc:43 ip4Address=192.168.1.116/24 ip6Address= ssid=gvy-34676 May 31 16:50:26 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:26.614+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" setupComplete=true May 31 16:50:26 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices May 31 16:50:26 myvolumiozero volumio[21870]: xcb_connection_has_error() returned true May 31 16:50:26 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 16:50:26 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 16:50:26 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 16:50:26 myvolumiozero volumio[21870]: amixer -c 2 info | grep "BossDAC" May 31 16:50:27 myvolumiozero volumio[21870]: xcb_connection_has_error() returned true May 31 16:50:27 myvolumiozero volumio[21870]: Card hw:2 'BossDAC'/'BossDAC' May 31 16:50:27 myvolumiozero volumio[21870]: amixer -c 0 info | grep "bcm2835 ALSA" May 31 16:50:27 myvolumiozero volumio[21870]: xcb_connection_has_error() returned true May 31 16:50:27 myvolumiozero volumio[21870]: amixer -c 1 info | grep "bcm2835 Headphones" May 31 16:50:27 myvolumiozero volumio[21870]: xcb_connection_has_error() returned true May 31 16:50:27 myvolumiozero volumio[21870]: Card hw:1 'Headphones'/'bcm2835 Headphones' May 31 16:50:27 myvolumiozero volumio[21870]: amixer -c 2 info | grep "BossDAC" May 31 16:50:27 myvolumiozero volumio[21870]: xcb_connection_has_error() returned true May 31 16:50:27 myvolumiozero volumio[21870]: Card hw:2 'BossDAC'/'BossDAC' May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.720+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" selectedOutputId=2 May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.731+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.102:50776 @ 0x2f388d0" latency=-229.827201ms timeout=10s May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.732+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.734+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.102:50776 @ 0x2f388d0" latency=-225.999221ms platform=PLATFORM_ANDROID version=5.260413.0 May 31 16:50:27 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:27 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:27 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.776+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" currentVersion=3.912 latestVersion=3.912 May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:27 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.785+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" name=MyVolumioZero May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.786+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" status=UPDATE_STATUS_NONE progress=0 May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.792+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" userId= May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.808+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" providers=9 May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.806+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" language=en May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.815+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" plugins=54 May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.855+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" timezone=Europe/Paris May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.856+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" available=false connected=false macAddress= ip4Address= ip6Address= May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.873+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" state=STATUS_PAUSED positionMs=41890 volume=55 May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.879+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.880+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" available=true connected=true macAddress=b8:27:eb:d4:fc:43 ip4Address=192.168.1.116/24 ip6Address= ssid=gvy-34676 May 31 16:50:27 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:27.882+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" setupComplete=true May 31 16:50:27 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices May 31 16:50:28 myvolumiozero volumio[21870]: xcb_connection_has_error() returned true May 31 16:50:28 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 16:50:28 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 16:50:28 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 16:50:28 myvolumiozero volumio[21870]: amixer -c 2 info | grep "BossDAC" May 31 16:50:28 myvolumiozero volumio[21870]: xcb_connection_has_error() returned true May 31 16:50:28 myvolumiozero volumio[21870]: Card hw:2 'BossDAC'/'BossDAC' May 31 16:50:28 myvolumiozero volumio[21870]: amixer -c 0 info | grep "bcm2835 ALSA" May 31 16:50:28 myvolumiozero volumio[21870]: xcb_connection_has_error() returned true May 31 16:50:28 myvolumiozero volumio[21870]: amixer -c 1 info | grep "bcm2835 Headphones" May 31 16:50:28 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:28 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705362. May 31 16:50:28 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:28 myvolumiozero volumio[21870]: xcb_connection_has_error() returned true May 31 16:50:28 myvolumiozero volumio[21870]: Card hw:1 'Headphones'/'bcm2835 Headphones' May 31 16:50:28 myvolumiozero volumio[21870]: amixer -c 2 info | grep "BossDAC" May 31 16:50:28 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:28 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:28.857+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.102:50776 @ 0x2f388d0" latency=-238.046771ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 31 16:50:29 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:29 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:29 myvolumiozero volumio[21870]: xcb_connection_has_error() returned true May 31 16:50:29 myvolumiozero volumio[21870]: Card hw:2 'BossDAC'/'BossDAC' May 31 16:50:29 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:29.155+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" selectedOutputId=2 May 31 16:50:29 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:29 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:29 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:29.220+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" currentVersion=3.912 latestVersion=3.912 May 31 16:50:29 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:29.223+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" status=UPDATE_STATUS_NONE progress=0 May 31 16:50:29 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:29.231+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" userId= May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:29 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:29.239+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" providers=9 May 31 16:50:29 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:29.242+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" plugins=54 May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:29 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:29 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:29.323+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" state=STATUS_PAUSED positionMs=41890 volume=55 May 31 16:50:29 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:29.324+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:50:29 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:29.552+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.102:50776 @ 0x2f388d0" latency=-241.274757ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 31 16:50:29 myvolumiozero volumio[21870]: verbose: New Socket.io Connection to 192.168.1.116:3000 from 192.168.1.102 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 16:50:29 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 16:50:31 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:31.206+02:00 level=INFO msg="new address was allocated" component=ble/conn old=53 new=54 May 31 16:50:31 myvolumiozero dbus-daemon[368]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.46" (uid=0 pid=1421 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=623 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") May 31 16:50:31 myvolumiozero sudo[9009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 16:50:31 myvolumiozero sudo[9009]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:32 myvolumiozero sudo[9009]: pam_unix(sudo:session): session closed for user root May 31 16:50:32 myvolumiozero sudo[9011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 16:50:32 myvolumiozero sudo[9011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:32 myvolumiozero sudo[9011]: pam_unix(sudo:session): session closed for user root May 31 16:50:33 myvolumiozero sudo[9027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 16:50:33 myvolumiozero sudo[9027]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:33 myvolumiozero sudo[9027]: pam_unix(sudo:session): session closed for user root May 31 16:50:33 myvolumiozero sudo[9040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 16:50:33 myvolumiozero sudo[9040]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:33 myvolumiozero sudo[9040]: pam_unix(sudo:session): session closed for user root May 31 16:50:34 myvolumiozero volumio[21870]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.102 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/CP1A.260505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 31 16:50:34 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:34 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705363. May 31 16:50:34 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:34 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:34 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:34 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:34 myvolumiozero volumio[21870]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.102 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/CP1A.260505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 31 16:50:34 myvolumiozero sudo[9065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 16:50:34 myvolumiozero sudo[9065]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:35 myvolumiozero sudo[9065]: pam_unix(sudo:session): session closed for user root May 31 16:50:35 myvolumiozero sudo[9067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 16:50:35 myvolumiozero sudo[9067]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:35 myvolumiozero sudo[9067]: pam_unix(sudo:session): session closed for user root May 31 16:50:35 myvolumiozero volumio[21870]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.102 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/CP1A.260505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 31 16:50:35 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 16:50:36 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 16:50:36 myvolumiozero sudo[9073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 16:50:36 myvolumiozero sudo[9073]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:36 myvolumiozero sudo[9073]: pam_unix(sudo:session): session closed for user root May 31 16:50:36 myvolumiozero sudo[9075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 16:50:36 myvolumiozero sudo[9075]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:36 myvolumiozero sudo[9075]: pam_unix(sudo:session): session closed for user root May 31 16:50:37 myvolumiozero sudo[9081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 16:50:37 myvolumiozero sudo[9081]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:37 myvolumiozero volumio[21870]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.102 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/CP1A.260505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 31 16:50:37 myvolumiozero sudo[9081]: pam_unix(sudo:session): session closed for user root May 31 16:50:37 myvolumiozero sudo[9083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 16:50:37 myvolumiozero sudo[9083]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:37 myvolumiozero sudo[9083]: pam_unix(sudo:session): session closed for user root May 31 16:50:37 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 16:50:37 myvolumiozero volumio[21870]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.102 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/CP1A.260505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 31 16:50:38 myvolumiozero sudo[9089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 16:50:38 myvolumiozero sudo[9089]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:38 myvolumiozero sudo[9089]: pam_unix(sudo:session): session closed for user root May 31 16:50:38 myvolumiozero sudo[9091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 16:50:38 myvolumiozero sudo[9091]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetVisibleSources May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 16:50:38 myvolumiozero sudo[9091]: pam_unix(sudo:session): session closed for user root May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 16:50:38 myvolumiozero volumio[21870]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 31 16:50:38 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:38 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:38 myvolumiozero volumio[21870]: info: Listing playlists May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 16:50:38 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:38 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 16:50:38 myvolumiozero volumio[21870]: verbose: New Socket.io Connection to 192.168.1.116 from 192.168.1.102 UA: Mozilla/5.0 (Linux; Android 16; Pixel 8a Build/CP1A.260505.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/148.0.7778.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetVisibleSources May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 16:50:38 myvolumiozero volumio[21870]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 31 16:50:38 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:38 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:38 myvolumiozero volumio[21870]: info: Listing playlists May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 16:50:38 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:38 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 16:50:38 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:38 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:38 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetVisibleSources May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 16:50:39 myvolumiozero volumio[21870]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 31 16:50:39 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:39 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:39 myvolumiozero volumio[21870]: info: Listing playlists May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 16:50:39 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 16:50:39 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:39.329+02:00 level=INFO msg="new address was allocated" component=ble/conn old=54 new=55 May 31 16:50:39 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:39 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705364. May 31 16:50:39 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:39 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:40 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:40 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 16:50:40 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:40 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 16:50:40 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:40 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:40 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 16:50:41 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 16:50:43 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:43 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 16:50:43 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:43 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 16:50:43 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:43 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:43 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:50:44 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:50:44 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 16:50:44 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:44 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 16:50:44 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:44 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:44 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:45 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:45 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705365. May 31 16:50:45 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:45 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:45 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:45 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPlay May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreStateMachine::play index undefined May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:50:45 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 6 May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreStateMachine::startPlaybackTimer May 31 16:50:45 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 6 May 31 16:50:45 myvolumiozero volumio[21870]: info: [1780239045740] ControllerTidal::resume May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 16:50:45 myvolumiozero volumio[21870]: info: ControllerMpd::resume May 31 16:50:45 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand play May 31 16:50:45 myvolumiozero volumio[21870]: info: May 31 16:50:45 myvolumiozero volumio[21870]: ---------------------------- MPD announces state update: player May 31 16:50:45 myvolumiozero volumio[21870]: info: sendMpdCommand play took 52 milliseconds May 31 16:50:45 myvolumiozero volumio[21870]: info: ControllerMpd::getState May 31 16:50:45 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand status May 31 16:50:45 myvolumiozero volumio[21870]: info: sendMpdCommand status took 10 milliseconds May 31 16:50:45 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseState May 31 16:50:45 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:50:45 myvolumiozero volumio[21870]: info: sendMpdCommand playlistinfo took 14 milliseconds May 31 16:50:45 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseTrackInfo May 31 16:50:45 myvolumiozero volumio[21870]: info: ControllerMpd::pushState May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreCommandRouter::servicePushState May 31 16:50:45 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 6 May 31 16:50:45 myvolumiozero volumio[21870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":43219,"duration":335,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1046 Kbps","isStreaming":false,"title":"274830709&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/274830709&soundQuality=LOSSLESS","trackType":"tidal"} May 31 16:50:45 myvolumiozero volumio[21870]: verbose: CURRENT POSITION 6 May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState stateService play May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState currentStatus pause May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:50:45 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:50:45 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:50:45 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:45 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:45.959+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=0 volume=55 May 31 16:50:45 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:45.978+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:50:46 myvolumiozero volumio[21870]: info: ------------------------------ 224ms May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:50:46 myvolumiozero volumio[21870]: info: ControllerMpd::seek May 31 16:50:46 myvolumiozero volumio[21870]: error: null May 31 16:50:46 myvolumiozero volumio[21870]: info: May 31 16:50:46 myvolumiozero volumio[21870]: ---------------------------- MPD announces state update: player May 31 16:50:46 myvolumiozero volumio[21870]: info: ControllerMpd::getState May 31 16:50:46 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand status May 31 16:50:46 myvolumiozero volumio[21870]: info: sendMpdCommand status took 12 milliseconds May 31 16:50:46 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseState May 31 16:50:46 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:50:46 myvolumiozero volumio[21870]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 16:50:46 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseTrackInfo May 31 16:50:46 myvolumiozero volumio[21870]: info: ControllerMpd::pushState May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreCommandRouter::servicePushState May 31 16:50:46 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 6 May 31 16:50:46 myvolumiozero volumio[21870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":335,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1046 Kbps","isStreaming":false,"title":"274830709&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/274830709&soundQuality=LOSSLESS","trackType":"tidal"} May 31 16:50:46 myvolumiozero volumio[21870]: verbose: CURRENT POSITION 6 May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState stateService play May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState currentStatus play May 31 16:50:46 myvolumiozero volumio[21870]: info: Received an update from plugin. extracting info from payload May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:50:46 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:50:46 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:50:46 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:50:46 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:50:46 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:47 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:46.993+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=0 volume=55 May 31 16:50:47 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:47.035+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=0 volume=55 May 31 16:50:47 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:47.014+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:50:47 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:47.045+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:50:47 myvolumiozero volumio[21870]: info: ------------------------------ 287ms May 31 16:50:47 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 16:50:47 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 16:50:49 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 16:50:50 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:50 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705366. May 31 16:50:50 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:50 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:50 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:50 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:53 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 16:50:53 myvolumiozero volumio[21870]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 16:50:53 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 16:50:54 myvolumiozero volumio[21870]: info: Received Get System Version May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 16:50:54 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:54 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 16:50:54 myvolumiozero volumio[21870]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 16:50:54 myvolumiozero volumio[21870]: info: Received Get System Version May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 16:50:54 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:54 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:54 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:55 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:50:55 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705367. May 31 16:50:55 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:50:55 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:50:56 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:50:56 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:50:56 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 16:50:56 myvolumiozero volumio[21870]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 16:50:56 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 16:50:56 myvolumiozero volumio[21870]: info: Received Get System Version May 31 16:50:56 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 16:50:56 myvolumiozero volumio[21870]: info: Received Get System Info May 31 16:50:56 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:50:56 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:50:56 myvolumiozero volumio[21870]: info: Discovery: Getting this device information May 31 16:50:56 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:56 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:50:59 myvolumiozero volumio[21870]: info: [1780239059243] ControllerTidal::seek May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 16:50:59 myvolumiozero volumio[21870]: info: ControllerMpd::seek May 31 16:50:59 myvolumiozero volumio[21870]: error: null May 31 16:50:59 myvolumiozero volumio[21870]: info: May 31 16:50:59 myvolumiozero volumio[21870]: ---------------------------- MPD announces state update: player May 31 16:50:59 myvolumiozero volumio[21870]: info: ControllerMpd::getState May 31 16:50:59 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand status May 31 16:50:59 myvolumiozero volumio[21870]: info: sendMpdCommand status took 11 milliseconds May 31 16:50:59 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseState May 31 16:50:59 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:50:59 myvolumiozero volumio[21870]: info: sendMpdCommand playlistinfo took 6 milliseconds May 31 16:50:59 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseTrackInfo May 31 16:50:59 myvolumiozero volumio[21870]: info: ControllerMpd::pushState May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::servicePushState May 31 16:50:59 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 6 May 31 16:50:59 myvolumiozero volumio[21870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":335,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"653 Kbps","isStreaming":false,"title":"274830709&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/274830709&soundQuality=LOSSLESS","trackType":"tidal"} May 31 16:50:59 myvolumiozero volumio[21870]: verbose: CURRENT POSITION 6 May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState stateService play May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState currentStatus play May 31 16:50:59 myvolumiozero volumio[21870]: info: Received an update from plugin. extracting info from payload May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:50:59 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:50:59 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:50:59 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:50:59 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:59 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:59.439+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=0 volume=55 May 31 16:50:59 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:59.457+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:50:59 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:59.468+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=0 volume=55 May 31 16:50:59 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:59.498+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:50:59 myvolumiozero volumio[21870]: info: ------------------------------ 294ms May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:50:59 myvolumiozero volumio[21870]: info: [1780239059680] ControllerTidal::previous May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:50:59 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 6 May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::stop May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::stPlaybackTimer May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:50:59 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:50:59 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 6 May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:50:59 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:50:59 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:50:59 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 6 May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceStop May 31 16:50:59 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 6 May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceStop May 31 16:50:59 myvolumiozero volumio[21870]: info: [1780239059800] ControllerTidal::stop May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 16:50:59 myvolumiozero volumio[21870]: info: ControllerMpd::stop May 31 16:50:59 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand stop May 31 16:50:59 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:59.820+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_STOPPED positionMs=0 volume=55 May 31 16:50:59 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:50:59.843+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:50:59 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:50:59 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:50:59 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:50:59 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:50:59 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:00 myvolumiozero volumio[21870]: info: May 31 16:51:00 myvolumiozero volumio[21870]: ---------------------------- MPD announces state update: player May 31 16:51:00 myvolumiozero volumio[21870]: info: sendMpdCommand stop took 180 milliseconds May 31 16:51:00 myvolumiozero volumio[21870]: info: ControllerMpd::getState May 31 16:51:00 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand status May 31 16:51:00 myvolumiozero volumio[21870]: info: sendMpdCommand status took 14 milliseconds May 31 16:51:00 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseState May 31 16:51:00 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:51:00 myvolumiozero volumio[21870]: info: sendMpdCommand playlistinfo took 39 milliseconds May 31 16:51:00 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseTrackInfo May 31 16:51:00 myvolumiozero volumio[21870]: info: ControllerMpd::pushState May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::servicePushState May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:51:00 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:51:00 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:51:00 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 3 May 31 16:51:00 myvolumiozero volumio[21870]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"274830709&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/274830709&soundQuality=LOSSLESS","trackType":"tidal"} May 31 16:51:00 myvolumiozero volumio[21870]: verbose: CURRENT POSITION 3 May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState stateService stop May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState currentStatus stop May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:51:00 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:51:00 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:51:00 myvolumiozero volumio[21870]: info: No code May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:51:00 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:51:00 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:51:00 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:00.135+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=360 volume=55 May 31 16:51:00 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:00.146+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=360 volume=55 May 31 16:51:00 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:00.150+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:51:00 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:00.172+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:51:00 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:00.164+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=360 volume=55 May 31 16:51:00 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:00.183+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830711 title="The Stone" May 31 16:51:00 myvolumiozero volumio[21870]: info: ------------------------------ 276ms May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:00 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:51:00 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::play index undefined May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:51:00 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 1 May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::startPlaybackTimer May 31 16:51:00 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 1 May 31 16:51:00 myvolumiozero volumio[21870]: info: [1780239060326] ControllerTidal::clearAddPlayTrack May 31 16:51:00 myvolumiozero volumio[21870]: info: Getting stream with soundQuality LOSSLESS May 31 16:51:00 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand stop May 31 16:51:00 myvolumiozero volumio[21870]: info: sendMpdCommand stop took 3 milliseconds May 31 16:51:00 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand clear May 31 16:51:00 myvolumiozero volumio[21870]: info: May 31 16:51:00 myvolumiozero volumio[21870]: ---------------------------- MPD announces system playlist update May 31 16:51:00 myvolumiozero volumio[21870]: info: Ignoring MPD Status Update May 31 16:51:00 myvolumiozero volumio[21870]: info: sendMpdCommand clear took 78 milliseconds May 31 16:51:00 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/274830706&soundQuality=LOSSLESS" May 31 16:51:00 myvolumiozero volumio[21870]: error: updateQueue error: null May 31 16:51:00 myvolumiozero volumio[21870]: info: May 31 16:51:00 myvolumiozero volumio[21870]: ---------------------------- MPD announces system playlist update May 31 16:51:00 myvolumiozero volumio[21870]: info: Ignoring MPD Status Update May 31 16:51:00 myvolumiozero volumio[21870]: info: ------------------------------ 30ms May 31 16:51:00 myvolumiozero volumio[21870]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/274830706&soundQuality=LOSSLESS" took 24 milliseconds May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 16:51:00 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand play May 31 16:51:00 myvolumiozero volumio[21870]: STREAMING PROXY: Handling url /?data=tidal://song/274830706&soundQuality=LOSSLESS May 31 16:51:00 myvolumiozero volumio[21870]: info: ------------------------------ 58ms May 31 16:51:00 myvolumiozero volumio[21870]: info: sendMpdCommand play took 48 milliseconds May 31 16:51:00 myvolumiozero volumio[21870]: info: Executing endpoint getStreamUrltidal May 31 16:51:00 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI May 31 16:51:00 myvolumiozero volumio[21870]: info: Refreshing TIDAL token May 31 16:51:01 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:51:01 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705368. May 31 16:51:01 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:51:01 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:51:01 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:51:01 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:51:02 myvolumiozero volumio[21870]: info: getStreamUrl took 606 milliseconds May 31 16:51:02 myvolumiozero volumio[21870]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEiczYzg0OTgyMWQ5OGZhNWIzNDEyZGQzYjNmM2IwY2YzZF82MS5tcDQ/0.flac?token=1780242661~NDlkYzI0NmVhOWY1MzUwYTU0MzA5ZGI1MmFkNTEwNGQyOWEwOTE5Yg== May 31 16:51:02 myvolumiozero volumio[21870]: STREAMING PROXY: Requesting upstream with range: none May 31 16:51:02 myvolumiozero volumio[21870]: STREAMING PROXY: Upstream status code: 200 May 31 16:51:02 myvolumiozero volumio[21870]: info: May 31 16:51:02 myvolumiozero volumio[21870]: ---------------------------- MPD announces state update: player May 31 16:51:02 myvolumiozero volumio[21870]: info: ControllerMpd::getState May 31 16:51:02 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand status May 31 16:51:02 myvolumiozero volumio[21870]: info: May 31 16:51:02 myvolumiozero volumio[21870]: ---------------------------- MPD announces state update: player May 31 16:51:02 myvolumiozero volumio[21870]: info: ControllerMpd::getState May 31 16:51:02 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand status May 31 16:51:02 myvolumiozero volumio[21870]: info: sendMpdCommand status took 69 milliseconds May 31 16:51:02 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseState May 31 16:51:02 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:51:02 myvolumiozero volumio[21870]: info: sendMpdCommand status took 85 milliseconds May 31 16:51:02 myvolumiozero volumio[21870]: info: sendMpdCommand playlistinfo took 64 milliseconds May 31 16:51:02 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseState May 31 16:51:02 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:51:02 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseTrackInfo May 31 16:51:02 myvolumiozero volumio[21870]: info: ControllerMpd::pushState May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreCommandRouter::servicePushState May 31 16:51:02 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 1 May 31 16:51:02 myvolumiozero volumio[21870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"274830706&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/274830706&soundQuality=LOSSLESS","trackType":"tidal"} May 31 16:51:02 myvolumiozero volumio[21870]: verbose: CURRENT POSITION 1 May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState stateService play May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState currentStatus stop May 31 16:51:02 myvolumiozero volumio[21870]: info: ------------------------------ 337ms May 31 16:51:02 myvolumiozero volumio[21870]: info: sendMpdCommand playlistinfo took 81 milliseconds May 31 16:51:02 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseTrackInfo May 31 16:51:02 myvolumiozero volumio[21870]: info: ControllerMpd::pushState May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreCommandRouter::servicePushState May 31 16:51:02 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 1 May 31 16:51:02 myvolumiozero volumio[21870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"274830706&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/274830706&soundQuality=LOSSLESS","trackType":"tidal"} May 31 16:51:02 myvolumiozero volumio[21870]: verbose: CURRENT POSITION 1 May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState stateService play May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState currentStatus play May 31 16:51:02 myvolumiozero volumio[21870]: info: Received an update from plugin. extracting info from payload May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:51:02 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:51:02 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:51:02 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:51:02 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:51:03 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:51:03 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:51:03 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:03.021+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=0 volume=55 May 31 16:51:03 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:03.061+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830706 title=Rapunzel May 31 16:51:03 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:03.113+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=0 volume=55 May 31 16:51:03 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:03.114+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830706 title=Rapunzel May 31 16:51:03 myvolumiozero volumio[21870]: info: ------------------------------ 1060ms May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:04 myvolumiozero volumio[21870]: info: [1780239064285] ControllerTidal::seek May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 16:51:04 myvolumiozero volumio[21870]: info: ControllerMpd::seek May 31 16:51:04 myvolumiozero volumio[21870]: error: null May 31 16:51:04 myvolumiozero volumio[21870]: info: May 31 16:51:04 myvolumiozero volumio[21870]: ---------------------------- MPD announces state update: player May 31 16:51:04 myvolumiozero volumio[21870]: info: ControllerMpd::getState May 31 16:51:04 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand status May 31 16:51:04 myvolumiozero volumio[21870]: info: sendMpdCommand status took 54 milliseconds May 31 16:51:04 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseState May 31 16:51:04 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:04 myvolumiozero volumio[21870]: info: ControllerMpd::previous May 31 16:51:04 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand previous May 31 16:51:04 myvolumiozero volumio[21870]: info: sendMpdCommand playlistinfo took 93 milliseconds May 31 16:51:04 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseTrackInfo May 31 16:51:04 myvolumiozero volumio[21870]: info: ControllerMpd::pushState May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreCommandRouter::servicePushState May 31 16:51:04 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 1 May 31 16:51:04 myvolumiozero volumio[21870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1068 Kbps","isStreaming":false,"title":"274830706&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/274830706&soundQuality=LOSSLESS","trackType":"tidal"} May 31 16:51:04 myvolumiozero volumio[21870]: verbose: CURRENT POSITION 1 May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState stateService play May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState currentStatus play May 31 16:51:04 myvolumiozero volumio[21870]: info: Received an update from plugin. extracting info from payload May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:51:04 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:51:04 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:51:04 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:51:04 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:51:04 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:51:04 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:04.900+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=268 volume=55 May 31 16:51:04 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:04.946+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830706 title=Rapunzel May 31 16:51:05 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:05.072+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_PLAYING positionMs=268 volume=55 May 31 16:51:05 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:05.093+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830706 title=Rapunzel May 31 16:51:05 myvolumiozero volumio[21870]: info: ------------------------------ 961ms May 31 16:51:05 myvolumiozero volumio[21870]: info: May 31 16:51:05 myvolumiozero volumio[21870]: ---------------------------- MPD announces state update: player May 31 16:51:05 myvolumiozero volumio[21870]: info: sendMpdCommand previous took 908 milliseconds May 31 16:51:05 myvolumiozero volumio[21870]: info: ControllerMpd::getState May 31 16:51:05 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand status May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:05 myvolumiozero volumio[21870]: info: [1780239065563] ControllerTidal::previous May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:05 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 1 May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::stop May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::stPlaybackTimer May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:05 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::pushState May 31 16:51:05 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 1 May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPushState May 31 16:51:05 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output update for this device May 31 16:51:05 myvolumiozero volumio[21870]: info: MRS: Pushing multiroomSync output May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioGetState May 31 16:51:05 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 1 May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceStop May 31 16:51:05 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 1 May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceStop May 31 16:51:05 myvolumiozero volumio[21870]: info: [1780239065967] ControllerTidal::stop May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService mpd May 31 16:51:05 myvolumiozero volumio[21870]: info: ControllerMpd::stop May 31 16:51:05 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand stop May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:05 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:06 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:06.087+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" state=STATUS_STOPPED positionMs=0 volume=55 May 31 16:51:06 myvolumiozero volumio5-onboarding[1421]: time=2026-05-31T16:51:06.123+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.102:50776,00:00:00:00:00:00%36 @ 0x2f388d0" id=tidal://song/274830706 title=Rapunzel May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:51:06 myvolumiozero volumio[21870]: STREAMING PROXY: Client closed connection May 31 16:51:06 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:51:06 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:51:06 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:06 myvolumiozero volumio[21870]: STREAMING PROXY: Upstream closed. Received 10656360 bytes May 31 16:51:06 myvolumiozero volumio[21870]: STREAMING PROXY: Incomplete transfer. Expected 45626460, got 10656360 May 31 16:51:06 myvolumiozero volumio[21870]: STREAMING PROXY: Retrying with range: bytes=10656360- (attempt 1) May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::play index undefined May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 4 May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::startPlaybackTimer May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 4 May 31 16:51:06 myvolumiozero volumio[21870]: info: [1780239066595] ControllerTidal::clearAddPlayTrack May 31 16:51:06 myvolumiozero volumio[21870]: info: Getting stream with soundQuality LOSSLESS May 31 16:51:06 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand stop May 31 16:51:06 myvolumiozero volumio[21870]: info: May 31 16:51:06 myvolumiozero volumio[21870]: ---------------------------- MPD announces state update: player May 31 16:51:06 myvolumiozero volumio[21870]: info: sendMpdCommand status took 1126 milliseconds May 31 16:51:06 myvolumiozero volumio[21870]: info: sendMpdCommand stop took 651 milliseconds May 31 16:51:06 myvolumiozero volumio[21870]: info: ControllerMpd::getState May 31 16:51:06 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand status May 31 16:51:06 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseState May 31 16:51:06 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:06 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::updateTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrackBlock May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:51:06 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:51:06 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:06 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705369. May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:51:06 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:51:06 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:51:06 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:51:06 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::serviceClearAddPlay May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::serviceClearAddPlayTracks May 31 16:51:06 myvolumiozero volumio[21870]: error: WARNING: No clearAddPlayTracks method for service tidal May 31 16:51:06 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:51:06 myvolumiozero volumio[21870]: info: sendMpdCommand stop took 256 milliseconds May 31 16:51:06 myvolumiozero volumio[21870]: info: sendMpdCommand status took 223 milliseconds May 31 16:51:06 myvolumiozero volumio[21870]: info: sendMpdCommand playlistinfo took 223 milliseconds May 31 16:51:06 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand clear May 31 16:51:06 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseState May 31 16:51:06 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 16:51:06 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseTrackInfo May 31 16:51:06 myvolumiozero volumio[21870]: info: ControllerMpd::pushState May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreCommandRouter::servicePushState May 31 16:51:06 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 11 May 31 16:51:06 myvolumiozero volumio[21870]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1464,"duration":360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"834 Kbps","isStreaming":false,"title":"274830706&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/274830706&soundQuality=LOSSLESS","trackType":"tidal"} May 31 16:51:06 myvolumiozero volumio[21870]: verbose: CURRENT POSITION 11 May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState stateService play May 31 16:51:06 myvolumiozero volumio[21870]: info: CoreStateMachine::syncState currentStatus stop May 31 16:51:06 myvolumiozero volumio[21870]: info: ------------------------------ 1543ms May 31 16:51:07 myvolumiozero volumio[21870]: info: May 31 16:51:07 myvolumiozero volumio[21870]: ---------------------------- MPD announces system playlist update May 31 16:51:07 myvolumiozero volumio[21870]: info: Ignoring MPD Status Update May 31 16:51:07 myvolumiozero volumio[21870]: info: sendMpdCommand clear took 164 milliseconds May 31 16:51:07 myvolumiozero volumio[21870]: info: sendMpdCommand playlistinfo took 164 milliseconds May 31 16:51:07 myvolumiozero volumio[21870]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/274830709&soundQuality=LOSSLESS" May 31 16:51:07 myvolumiozero volumio[21870]: verbose: ControllerMpd::parseTrackInfo May 31 16:51:07 myvolumiozero volumio[21870]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 31 16:51:07 myvolumiozero volumio[21870]: info: ------------------------------ 429ms May 31 16:51:07 myvolumiozero volumio[21870]: info: CoreCommandRouter::volumioPrevious May 31 16:51:07 myvolumiozero volumio[21870]: info: CoreStateMachine::previous May 31 16:51:07 myvolumiozero volumio[21870]: info: CorePlayQueue::getTrack 11 May 31 16:51:07 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:51:07 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:51:07 myvolumiozero volumio[21870]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 16:51:07 myvolumiozero volumio[21870]: TypeError: Cannot read property 'service' of undefined May 31 16:51:07 myvolumiozero volumio[21870]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1329:97) May 31 16:51:07 myvolumiozero volumio[21870]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:97:28) May 31 16:51:07 myvolumiozero volumio[21870]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:270:33) May 31 16:51:07 myvolumiozero volumio[21870]: at Socket.emit (events.js:315:20) May 31 16:51:07 myvolumiozero volumio[21870]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 31 16:51:07 myvolumiozero volumio[21870]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 31 16:51:07 myvolumiozero volumio[21870]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 16:51:07 myvolumiozero volumio[21870]: STREAMING PROXY: Resuming with range: bytes=10656360- May 31 16:51:07 myvolumiozero volumio[21870]: STREAMING PROXY: Resume upstream status code: 206 May 31 16:51:12 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:51:12 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705370. May 31 16:51:12 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:51:12 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:51:12 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:51:12 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:51:13 myvolumiozero volumio-remote-updater[509]: [2026-05-31 16:51:13] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 31 16:51:17 myvolumiozero systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 31 16:51:17 myvolumiozero systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 705371. May 31 16:51:17 myvolumiozero systemd[1]: Stopped Volumio Qobuz Connect Service. May 31 16:51:18 myvolumiozero systemd[1]: Started Volumio Qobuz Connect Service. May 31 16:51:18 myvolumiozero volumio-remote-updater[509]: [2026-05-31 16:51:18] [connect] Successful connection May 31 16:51:18 myvolumiozero systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 31 16:51:18 myvolumiozero systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 31 16:51:21 myvolumiozero volumio[21870]: STREAMING PROXY: Resume ended. Received 34970100 bytes May 31 16:51:21 myvolumiozero sudo[9172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-31 16:50 May 31 16:51:21 myvolumiozero sudo[9172]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"