Apr 14 19:01:01 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:01.084+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" userId=PyUtJASU8LR2E2LGe48W1593EVy2 Apr 14 19:01:01 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 19:01:01 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 19:01:01 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 19:01:01 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:01.777+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Apr 14 19:01:01 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:01.777+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Apr 14 19:01:01 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:01.777+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Apr 14 19:01:01 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:01.778+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" providers=9 Apr 14 19:01:01 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 19:01:01 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 19:01:01 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 19:01:02 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:02.185+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" plugins=63 Apr 14 19:01:02 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:02 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:02 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:02.188+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_STOPPED positionMs=0 volume=20 Apr 14 19:01:02 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:02.188+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=spotify:track:3M8FzayQWtkvOhqMn2V4T2 title="Lean on Me" Apr 14 19:01:02 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:02.286+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.215:54358 @ 0x1b80c60" latency=7.706991ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Apr 14 19:01:03 luxor sudo[10290]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 19:01:03 luxor sudo[10290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:01:03 luxor sudo[10292]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 19:01:03 luxor sudo[10292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:01:03 luxor sudo[10292]: pam_unix(sudo:session): session closed for user root Apr 14 19:01:03 luxor sudo[10290]: pam_unix(sudo:session): session closed for user root Apr 14 19:01:03 luxor volumio[6856]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.215 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Apr 14 19:01:03 luxor sudo[10296]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 19:01:03 luxor sudo[10296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:01:03 luxor sudo[10296]: pam_unix(sudo:session): session closed for user root Apr 14 19:01:03 luxor sudo[10298]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 19:01:03 luxor sudo[10298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:01:03 luxor sudo[10298]: pam_unix(sudo:session): session closed for user root Apr 14 19:01:03 luxor volumio[6856]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.215 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:03 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 19:01:03 luxor volumio[6856]: info: Received Get System Info Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 19:01:03 luxor volumio[6856]: info: Discovery: Getting this device information Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:03 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:03 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:03 luxor volumio[6856]: info: Listing playlists Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 19:01:03 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 19:01:04 luxor volumio[6856]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 19:01:05 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 19:01:05 luxor volumio[6856]: info: Received Get System Info Apr 14 19:01:05 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 19:01:05 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 19:01:05 luxor volumio[6856]: info: Discovery: Getting this device information Apr 14 19:01:05 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:05 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:05 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 19:01:05 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 19:01:05 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 14 19:01:07 luxor volumio[6856]: info: CoreCommandRouter::volumioPlay Apr 14 19:01:07 luxor volumio[6856]: info: CoreStateMachine::play index undefined Apr 14 19:01:07 luxor volumio[6856]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 19:01:07 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:07 luxor volumio[6856]: info: CoreStateMachine::startPlaybackTimer Apr 14 19:01:07 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:07 luxor volumio[6856]: info: [1776186067932] ControllerSpotify::clearAddPlayTrack Apr 14 19:01:07 luxor volumio[6856]: info: Sending Spotify command with payload to local API: /player/play Apr 14 19:01:12 luxor volumio[6856]: info: CoreCommandRouter::volumioPlay Apr 14 19:01:12 luxor volumio[6856]: info: CoreStateMachine::play index undefined Apr 14 19:01:12 luxor volumio[6856]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 19:01:12 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:12 luxor volumio[6856]: info: CoreStateMachine::startPlaybackTimer Apr 14 19:01:12 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:12 luxor volumio[6856]: info: [1776186072260] ControllerSpotify::clearAddPlayTrack Apr 14 19:01:12 luxor volumio[6856]: info: Sending Spotify command with payload to local API: /player/play Apr 14 19:01:13 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 19:01:14 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 19:01:14 luxor volumio[6856]: info: Received Get System Info Apr 14 19:01:14 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 19:01:14 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 19:01:14 luxor volumio[6856]: info: Discovery: Getting this device information Apr 14 19:01:14 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:14 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:14 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 19:01:14 luxor volumio[6856]: info: CoreCommandRouter::volumioGetQueue Apr 14 19:01:14 luxor volumio[6856]: info: CoreStateMachine::getQueue Apr 14 19:01:14 luxor volumio[6856]: info: CorePlayQueue::getQueue Apr 14 19:01:16 luxor volumio[6856]: info: CoreCommandRouter::volumioRandom Apr 14 19:01:16 luxor volumio[6856]: info: CoreCommandRouter::writePlayerControls Apr 14 19:01:16 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:16 luxor volumio[6856]: info: CoreStateMachine::setRepeat true single false Apr 14 19:01:16 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:16 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:16 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 19:01:16 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:16 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:16.519+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_STOPPED positionMs=4251 volume=20 Apr 14 19:01:16 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:16.519+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_STOPPED positionMs=4251 volume=20 Apr 14 19:01:16 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:16.519+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_STOPPED positionMs=4251 volume=20 Apr 14 19:01:16 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:16.519+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=spotify:track:3M8FzayQWtkvOhqMn2V4T2 title="Lean on Me" Apr 14 19:01:16 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:16.519+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=spotify:track:3M8FzayQWtkvOhqMn2V4T2 title="Lean on Me" Apr 14 19:01:16 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:16.519+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=spotify:track:3M8FzayQWtkvOhqMn2V4T2 title="Lean on Me" Apr 14 19:01:16 luxor volumio[6856]: info: FusionDsp - Volumio is not playing Apr 14 19:01:16 luxor volumio[6856]: info: FusionDsp - Clipped samples monitor stopped Apr 14 19:01:16 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:20 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:20.795+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.215:54358 @ 0x1b80c60" latency=27.227343ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Apr 14 19:01:21 luxor sudo[10333]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 19:01:21 luxor sudo[10333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:01:21 luxor sudo[10335]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 19:01:21 luxor sudo[10333]: pam_unix(sudo:session): session closed for user root Apr 14 19:01:21 luxor sudo[10335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:01:21 luxor sudo[10335]: pam_unix(sudo:session): session closed for user root Apr 14 19:01:21 luxor volumio[6856]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.215 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 19:01:21 luxor sudo[10339]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 19:01:21 luxor sudo[10339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:01:21 luxor sudo[10339]: pam_unix(sudo:session): session closed for user root Apr 14 19:01:21 luxor sudo[10341]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 19:01:21 luxor sudo[10341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:01:21 luxor sudo[10341]: pam_unix(sudo:session): session closed for user root Apr 14 19:01:21 luxor volumio[6856]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.215 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:21 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 19:01:21 luxor volumio[6856]: info: Received Get System Info Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 19:01:21 luxor volumio[6856]: info: Discovery: Getting this device information Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:21 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:21 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:21 luxor volumio[6856]: info: Listing playlists Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 19:01:21 luxor volumio[6856]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 19:01:22 luxor volumio[6856]: info: CoreCommandRouter::volumioPlay Apr 14 19:01:22 luxor volumio[6856]: info: CoreStateMachine::play index undefined Apr 14 19:01:22 luxor volumio[6856]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 19:01:22 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:22 luxor volumio[6856]: info: CoreStateMachine::startPlaybackTimer Apr 14 19:01:22 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:22 luxor volumio[6856]: info: [1776186082789] ControllerSpotify::clearAddPlayTrack Apr 14 19:01:22 luxor volumio[6856]: info: Sending Spotify command with payload to local API: /player/play Apr 14 19:01:22 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 14 19:01:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 19:01:23 luxor volumio[6856]: info: Received Get System Info Apr 14 19:01:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 19:01:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 19:01:23 luxor volumio[6856]: info: Discovery: Getting this device information Apr 14 19:01:23 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:23 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 19:01:24 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 19:01:24 luxor volumio[6856]: info: Received Get System Info Apr 14 19:01:24 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 19:01:24 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 19:01:24 luxor volumio[6856]: info: Discovery: Getting this device information Apr 14 19:01:24 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:24 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:24 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 19:01:31 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 19:01:33 luxor volumio[6856]: info: CoreCommandRouter::volumioGetQueue Apr 14 19:01:33 luxor volumio[6856]: info: CoreStateMachine::getQueue Apr 14 19:01:33 luxor volumio[6856]: info: CorePlayQueue::getQueue Apr 14 19:01:34 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 19:01:34 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 19:01:34 luxor volumio[6856]: info: Preload queue cleared Apr 14 19:01:36 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 19:01:36 luxor volumio[6856]: info: Preload queue cleared Apr 14 19:01:39 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 19:01:39 luxor volumio[6856]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 19:01:39 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 19:01:39 luxor volumio[6856]: info: Received Get System Version Apr 14 19:01:39 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 19:01:39 luxor volumio[6856]: info: Received Get System Info Apr 14 19:01:39 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 19:01:39 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 19:01:39 luxor volumio[6856]: info: Discovery: Getting this device information Apr 14 19:01:39 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:01:39 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:39 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 19:01:40 luxor volumio[6856]: info: Preload queue cleared Apr 14 19:01:40 luxor volumio[6856]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::ClearQueue Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::stop Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 19:01:40 luxor volumio[6856]: info: CorePlayQueue::clearPlayQueue Apr 14 19:01:40 luxor volumio[6856]: info: CorePlayQueue::saveQueue Apr 14 19:01:40 luxor volumio[6856]: info: CoreCommandRouter::volumioPushQueue Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::addQueueItems Apr 14 19:01:40 luxor volumio[6856]: info: CorePlayQueue::addQueueItems Apr 14 19:01:40 luxor volumio[6856]: info: Preload queue cleared Apr 14 19:01:40 luxor volumio[6856]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s37309 Apr 14 19:01:40 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 19:01:40 luxor volumio[6856]: info: CoreCommandRouter::volumioPushQueue Apr 14 19:01:40 luxor volumio[6856]: info: CorePlayQueue::saveQueue Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::updateTrackBlock Apr 14 19:01:40 luxor volumio[6856]: info: CorePlayQueue::getTrackBlock Apr 14 19:01:40 luxor volumio[6856]: info: CoreCommandRouter::volumioPlay Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::play index 0 Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::stop Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::play index undefined Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 19:01:40 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:40 luxor volumio[6856]: info: CoreStateMachine::startPlaybackTimer Apr 14 19:01:40 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:40 luxor volumio[6856]: info: [1776186100826] ControllerWebradio::clearAddPlayTrack Apr 14 19:01:40 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 19:01:40 luxor volumio[6856]: info: sendMpdCommand stop took 3 milliseconds Apr 14 19:01:40 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 19:01:40 luxor volumio[6856]: info: Apr 14 19:01:40 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:40 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:40 luxor volumio[6856]: info: sendMpdCommand clear took 1 milliseconds Apr 14 19:01:40 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand load "http://live-icy.gss.dr.dk/A/A08L.mp3.m3u" Apr 14 19:01:40 luxor volumio[6856]: info: Apr 14 19:01:40 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:40 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:40 luxor volumio[6856]: info: Apr 14 19:01:40 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:40 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:40 luxor volumio[6856]: info: Apr 14 19:01:40 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:40 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:40 luxor volumio[6856]: error: updateQueue error: null Apr 14 19:01:40 luxor volumio[6856]: info: ------------------------------ 4ms Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand load "http://live-icy.gss.dr.dk/A/A08L.mp3.m3u" took 394 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand play Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:41 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 394ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 395ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 394ms Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:41 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:41 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:41 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand play took 3 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 2ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 1ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 1ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 2ms Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 3 milliseconds Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 5 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 5 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 4 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A08L.mp3","artist":null,"album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus stop Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 11ms Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 7 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 2 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 2 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 1 milliseconds Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A08L.mp3","artist":null,"album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A08L.mp3","artist":null,"album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A08L.mp3","artist":null,"album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.642+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.643+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.643+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.643+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.643+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.643+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.645+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.645+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.645+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.645+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.645+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.645+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.645+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.646+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.646+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.646+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.646+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.646+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.646+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.647+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.647+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.647+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.647+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.647+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.647+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.648+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.648+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.648+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.648+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.648+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.649+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.649+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.649+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.649+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.649+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.649+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="DR P4 København" Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 47ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 48ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 47ms Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:41 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"/ Fallulah - Sort som solen","artist":"P4 KBH","album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"/ Fallulah - Sort som solen","artist":"P4 KBH","album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"/ Fallulah - Sort som solen","artist":"P4 KBH","album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"/ Fallulah - Sort som solen","artist":"P4 KBH","album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.673+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.673+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.673+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.673+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.673+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.673+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.674+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.674+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.674+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.674+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.674+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.674+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.674+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.674+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.674+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.675+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.675+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.675+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.675+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.675+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.675+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.675+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.675+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.675+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.676+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.676+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.676+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.676+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.676+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.676+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.677+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.677+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.677+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.677+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.677+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.677+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.678+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.678+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.678+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.678+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.678+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.678+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.679+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.679+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.679+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=0 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.679+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.679+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.679+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 82ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 75ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 75ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 74ms Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:41 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:41 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces system playlist update Apr 14 19:01:41 luxor volumio[6856]: info: Ignoring MPD Status Update Apr 14 19:01:41 luxor volumio[6856]: info: Apr 14 19:01:41 luxor volumio[6856]: ---------------------------- MPD announces state update: player Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::getState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand status Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 63ms Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 62 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 24ms Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 24 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 24ms Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 24 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 24ms Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand status took 23 milliseconds Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseState Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 14 19:01:41 luxor volumio[6856]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: verbose: ControllerMpd::parseTrackInfo Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1465,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"/ Fallulah - Sort som solen","artist":"P4 KBH","album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1844,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"/ Fallulah - Sort som solen","artist":"P4 KBH","album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1844,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"/ Fallulah - Sort som solen","artist":"P4 KBH","album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: ControllerMpd::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::servicePushState Apr 14 19:01:41 luxor volumio[6856]: info: CorePlayQueue::getTrack 0 Apr 14 19:01:41 luxor volumio[6856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1844,"duration":0,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"/ Fallulah - Sort som solen","artist":"P4 KBH","album":null,"uri":"http://live-icy.dr.dk:80/A/A08L.mp3","trackType":"mp3"} Apr 14 19:01:41 luxor volumio[6856]: verbose: CURRENT POSITION 0 Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState stateService play Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::syncState currentStatus play Apr 14 19:01:41 luxor volumio[6856]: info: Received an update from plugin. extracting info from payload Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreStateMachine::pushState Apr 14 19:01:41 luxor volumio[6856]: info: CoreCommandRouter::volumioPushState Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.736+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.736+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.737+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.736+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.738+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" state=STATUS_PLAYING positionMs=152 volume=20 Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%92,192.168.1.241:54939 @ 0x1b1db30" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.241:54939 @ 0x198e0c0" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio5-onboarding[2168]: time=2026-04-14T19:01:41.739+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.215:54358 @ 0x1b80c60" id=http://live-icy.dr.dk:80/A/A08L.mp3 title="/ Fallulah - Sort som solen" Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 90ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 52ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 52ms Apr 14 19:01:41 luxor volumio[6856]: info: ------------------------------ 51ms Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - Volumio is playing Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Apr 14 19:01:41 luxor volumio[6856]: info: FusionDsp - ---- read samplerate from file: 48000 Apr 14 19:01:41 luxor volumio[6856]: info: camilladsp stopping service pid 7301... Apr 14 19:01:42 luxor volumio[6856]: grep: /proc/7301/cmdline: binary file matches Apr 14 19:01:42 luxor volumio[6856]: grep: /proc/7301/cmdline: binary file matches Apr 14 19:01:42 luxor volumio[6856]: grep: /proc/7301/cmdline: binary file matches Apr 14 19:01:42 luxor volumio[6856]: grep: /proc/7301/cmdline: binary file matches Apr 14 19:01:42 luxor volumio[6856]: grep: /proc/7301/cmdline: binary file matches Apr 14 19:01:42 luxor volumio[6856]: grep: /proc/7301/cmdline: binary file matches Apr 14 19:01:42 luxor volumio[6856]: info: camilladsp service terminated, instance 1 Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 14 19:01:42 luxor volumio[6856]: info: camilladsp service started and running in background, instance 1 Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 16000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Apr 14 19:01:42 luxor volumio[6856]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Apr 14 19:01:42 luxor volumio[6856]: info: camilladsp respawn in 100 ms (attempt 1/10) Apr 14 19:01:44 luxor volumio[6856]: info: FusionDsp - Clipping Monitor started Apr 14 19:02:09 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 19:02:09 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 19:02:09 luxor volumio[6856]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 19:02:12 luxor volumio[6856]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 19:02:22 luxor volumio[6856]: info: Received OAUTH Data Apr 14 19:02:22 luxor volumio[6856]: info: Executing Spotify Oauth Login Apr 14 19:02:22 luxor volumio[6856]: info: Saving Spotify Refresh Token Apr 14 19:02:23 luxor volumio[6856]: info: New Spotify access tokenBQBjiB95Jf... Apr 14 19:02:23 luxor volumio[6856]: info: Spotify credentials grant success - running version from March 24, 2019 Apr 14 19:02:23 luxor sudo[10581]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 19:02:23 luxor sudo[10581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:02:23 luxor sudo[10581]: pam_unix(sudo:session): session closed for user root Apr 14 19:02:23 luxor sudo[10583]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 19:02:23 luxor sudo[10583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:02:23 luxor sudo[10583]: pam_unix(sudo:session): session closed for user root Apr 14 19:02:23 luxor volumio[6856]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.215 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 19:02:23 luxor volumio[6856]: SPOTIFY: User informations: {"country":"SE","display_name":"bztsondh","email":"szpotify@sondh.se","explicit_content":{"filter_enabled":true,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/bztsondh"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/bztsondh","id":"bztsondh","images":[],"product":"premium","type":"user","uri":"spotify:user:bztsondh"} Apr 14 19:02:23 luxor volumio[6856]: info: Creating Spotify config file Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 19:02:23 luxor volumio[6856]: info: Spotify config file written Apr 14 19:02:23 luxor sudo[10588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Apr 14 19:02:23 luxor sudo[10588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:02:23 luxor systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Apr 14 19:02:23 luxor systemd[1]: go-librespot-daemon.service: Killing process 8211 (go-librespot) with signal SIGKILL. Apr 14 19:02:23 luxor systemd[1]: go-librespot-daemon.service: Deactivated successfully. Apr 14 19:02:23 luxor systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 19:02:23 luxor volumio[6856]: info: Received Get System Info Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 19:02:23 luxor volumio[6856]: info: Discovery: Getting this device information Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::volumioGetState Apr 14 19:02:23 luxor volumio[6856]: info: Listing playlists Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 19:02:23 luxor volumio[6856]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 19:02:23 luxor volumio[6856]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Apr 14 19:02:23 luxor volumio[6856]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 19:02:23 luxor volumio[6856]: Error: socket hang up Apr 14 19:02:23 luxor volumio[6856]: at connResetException (node:internal/errors:720:14) Apr 14 19:02:23 luxor volumio[6856]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 14 19:02:23 luxor volumio[6856]: at Socket.emit (node:events:526:35) Apr 14 19:02:23 luxor volumio[6856]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 14 19:02:23 luxor volumio[6856]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 14 19:02:23 luxor volumio[6856]: code: 'ECONNRESET', Apr 14 19:02:23 luxor volumio[6856]: response: undefined Apr 14 19:02:23 luxor volumio[6856]: } Apr 14 19:02:23 luxor volumio[6856]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 19:02:23 luxor systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 14 19:02:23 luxor go-librespot[10590]: go-librespot daemon starting... Apr 14 19:02:23 luxor sudo[10588]: pam_unix(sudo:session): session closed for user root Apr 14 19:02:23 luxor go-librespot[10594]: time="2026-04-14T19:02:23+02:00" level=info msg="running go-librespot 0.7.1" Apr 14 19:02:23 luxor go-librespot[10594]: time="2026-04-14T19:02:23+02:00" level=debug msg="app state loaded" Apr 14 19:02:23 luxor go-librespot[10594]: time="2026-04-14T19:02:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 14 19:02:23 luxor sudo[10613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 19:01' Apr 14 19:02:23 luxor sudo[10613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"