Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:00 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 12:27:00 volumio volumio[1073]: info: Received Get System Info Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 12:27:00 volumio volumio[1073]: info: Discovery: Getting this device information Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:00 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:00 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:00 volumio volumio[1073]: info: Listing playlists Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 12:27:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 12:27:01 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 22 12:27:01 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 12:27:01 volumio volumio[1073]: info: Received Get System Info Feb 22 12:27:01 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 12:27:01 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 12:27:01 volumio volumio[1073]: info: Discovery: Getting this device information Feb 22 12:27:01 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:01 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:01 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 12:27:02 volumio go-librespot[1502]: time="2026-02-22T12:27:02+01:00" level=debug msg="fetched chunk 8/13, size: 524288" uri="spotify:track:3Wj2bla9BKs0tcbmpq1p71" Feb 22 12:27:03 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 12:27:03 volumio volumio[1073]: info: Received Get System Info Feb 22 12:27:03 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 12:27:03 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 12:27:03 volumio volumio[1073]: info: Discovery: Getting this device information Feb 22 12:27:03 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:03 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:03 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 12:27:15 volumio volumio[1073]: verbose: New Socket.io Connection to 192.168.0.45 from 192.168.0.69 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: 6 Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:15 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 12:27:15 volumio volumio[1073]: info: Received Get System Info Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 12:27:15 volumio volumio[1073]: info: Discovery: Getting this device information Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:15 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:15 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:15 volumio volumio[1073]: info: Listing playlists Feb 22 12:27:15 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 12:27:16 volumio volumio[1073]: info: CoreCommandRouter::volumioGetQueue Feb 22 12:27:16 volumio volumio[1073]: info: CoreStateMachine::getQueue Feb 22 12:27:16 volumio volumio[1073]: info: CorePlayQueue::getQueue Feb 22 12:27:16 volumio go-librespot[1502]: time="2026-02-22T12:27:16+01:00" level=debug msg="fetched chunk 9/13, size: 524288" uri="spotify:track:3Wj2bla9BKs0tcbmpq1p71" Feb 22 12:27:21 volumio volumio[1073]: info: VolumeController::SetAlsaVolume38 Feb 22 12:27:21 volumio volumio[1073]: info: CoreStateMachine::pushState Feb 22 12:27:21 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:21 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 12:27:21 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Feb 22 12:27:21 volumio volumio5-onboarding[1402]: time=2026-02-22T12:27:21.629+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.69:51284 @ 0x2e19200" state=STATUS_PLAYING positionMs=82638 volume=38 Feb 22 12:27:21 volumio volumio5-onboarding[1402]: time=2026-02-22T12:27:21.629+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.69:51284 @ 0x2e19200" id=spotify:track:3Wj2bla9BKs0tcbmpq1p71 title="Fire Wire - Radio Edit" Feb 22 12:27:21 volumio volumio[1073]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Feb 22 12:27:21 volumio volumio[1073]: SPOTIFY: SPOTIFY VOLUME 100 Feb 22 12:27:21 volumio volumio[1073]: SPOTIFY: VOLUMIO VOLUME 38 Feb 22 12:27:21 volumio volumio[1073]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 22 12:27:21 volumio volumio[1073]: info: Setting Spotify Volume from Volumio: 38 Feb 22 12:27:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 12:27:22 volumio volumio[1073]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 22 12:27:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 22 12:27:22 volumio volumio[1073]: info: Received Get System Version Feb 22 12:27:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 12:27:22 volumio volumio[1073]: info: Received Get System Info Feb 22 12:27:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 12:27:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 12:27:22 volumio volumio[1073]: info: Discovery: Getting this device information Feb 22 12:27:22 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:22 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 12:27:22 volumio volumio[1073]: info: VolumeController::SetAlsaVolume45 Feb 22 12:27:22 volumio volumio[1073]: info: CoreStateMachine::pushState Feb 22 12:27:22 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:22 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 12:27:22 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Feb 22 12:27:22 volumio volumio5-onboarding[1402]: time=2026-02-22T12:27:22.931+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.69:51284 @ 0x2e19200" state=STATUS_PLAYING positionMs=83890 volume=45 Feb 22 12:27:22 volumio volumio5-onboarding[1402]: time=2026-02-22T12:27:22.932+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.69:51284 @ 0x2e19200" id=spotify:track:3Wj2bla9BKs0tcbmpq1p71 title="Fire Wire - Radio Edit" Feb 22 12:27:22 volumio volumio[1073]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Feb 22 12:27:22 volumio volumio[1073]: SPOTIFY: SPOTIFY VOLUME 38 Feb 22 12:27:22 volumio volumio[1073]: SPOTIFY: VOLUMIO VOLUME 45 Feb 22 12:27:22 volumio volumio[1073]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 22 12:27:22 volumio volumio[1073]: info: Setting Spotify Volume from Volumio: 45 Feb 22 12:27:24 volumio volumio[1073]: SPOTIFY: SETTING SPOTIFY VOLUME 45 Feb 22 12:27:24 volumio volumio[1073]: info: Sending Spotify command with payload to local API: /player/volume Feb 22 12:27:24 volumio go-librespot[1502]: time="2026-02-22T12:27:24+01:00" level=debug msg="update volume requested to 29490/65535" Feb 22 12:27:24 volumio go-librespot[1502]: time="2026-02-22T12:27:24+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 22 12:27:24 volumio go-librespot[1502]: time="2026-02-22T12:27:24+01:00" level=trace msg="emitting websocket event: volume" Feb 22 12:27:24 volumio volumio[1073]: SPOTIFY: received: {"type":"volume","data":{"value":45,"max":100}} Feb 22 12:27:24 volumio volumio[1073]: SPOTIFY: RECEIVED SPOTIFY VOLUME 45 Feb 22 12:27:26 volumio go-librespot[1502]: time="2026-02-22T12:27:26+01:00" level=trace msg="sent dealer ping" Feb 22 12:27:26 volumio go-librespot[1502]: time="2026-02-22T12:27:26+01:00" level=trace msg="received dealer pong" Feb 22 12:27:27 volumio volumio[1073]: info: VolumeController::SetAlsaVolume68 Feb 22 12:27:27 volumio volumio[1073]: info: CoreStateMachine::pushState Feb 22 12:27:27 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:27 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 12:27:27 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Feb 22 12:27:27 volumio volumio5-onboarding[1402]: time=2026-02-22T12:27:27.375+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.69:51284 @ 0x2e19200" state=STATUS_PLAYING positionMs=88394 volume=68 Feb 22 12:27:27 volumio volumio5-onboarding[1402]: time=2026-02-22T12:27:27.376+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.69:51284 @ 0x2e19200" id=spotify:track:3Wj2bla9BKs0tcbmpq1p71 title="Fire Wire - Radio Edit" Feb 22 12:27:27 volumio volumio[1073]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Feb 22 12:27:27 volumio volumio[1073]: SPOTIFY: SPOTIFY VOLUME 45 Feb 22 12:27:27 volumio volumio[1073]: SPOTIFY: VOLUMIO VOLUME 68 Feb 22 12:27:27 volumio volumio[1073]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 22 12:27:27 volumio volumio[1073]: info: Setting Spotify Volume from Volumio: 68 Feb 22 12:27:28 volumio volumio[1073]: SPOTIFY: SETTING SPOTIFY VOLUME 68 Feb 22 12:27:28 volumio volumio[1073]: info: Sending Spotify command with payload to local API: /player/volume Feb 22 12:27:28 volumio go-librespot[1502]: time="2026-02-22T12:27:28+01:00" level=debug msg="update volume requested to 44563/65535" Feb 22 12:27:29 volumio go-librespot[1502]: time="2026-02-22T12:27:29+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 22 12:27:29 volumio go-librespot[1502]: time="2026-02-22T12:27:29+01:00" level=trace msg="emitting websocket event: volume" Feb 22 12:27:29 volumio volumio[1073]: SPOTIFY: received: {"type":"volume","data":{"value":68,"max":100}} Feb 22 12:27:29 volumio volumio[1073]: SPOTIFY: RECEIVED SPOTIFY VOLUME 68 Feb 22 12:27:30 volumio go-librespot[1502]: time="2026-02-22T12:27:30+01:00" level=debug msg="fetched chunk 10/13, size: 524288" uri="spotify:track:3Wj2bla9BKs0tcbmpq1p71" Feb 22 12:27:30 volumio volumio5-onboarding[1402]: time=2026-02-22T12:27:30.832+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.69:51284 @ 0x2e19200" latency=70.561388ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 22 12:27:31 volumio sudo[3703]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 12:27:31 volumio sudo[3703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:27:31 volumio sudo[3703]: pam_unix(sudo:session): session closed for user root Feb 22 12:27:31 volumio sudo[3705]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 12:27:31 volumio sudo[3705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:27:31 volumio sudo[3705]: pam_unix(sudo:session): session closed for user root Feb 22 12:27:31 volumio volumio[1073]: verbose: New Socket.io Connection to 192.168.0.45 from 192.168.0.69 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: 6 Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:31 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 12:27:31 volumio volumio[1073]: info: Received Get System Info Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 12:27:31 volumio volumio[1073]: info: Discovery: Getting this device information Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:31 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:31 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:31 volumio volumio[1073]: info: Listing playlists Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 12:27:31 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 12:27:31 volumio sudo[3709]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 12:27:31 volumio sudo[3709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:27:31 volumio sudo[3711]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 12:27:31 volumio sudo[3711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 12:27:31 volumio sudo[3709]: pam_unix(sudo:session): session closed for user root Feb 22 12:27:31 volumio sudo[3711]: pam_unix(sudo:session): session closed for user root Feb 22 12:27:31 volumio volumio[1073]: verbose: New Socket.io Connection to 192.168.0.45 from 192.168.0.69 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: 6 Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::volumioGetVisibleSources Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:32 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 22 12:27:32 volumio volumio[1073]: info: Received Get System Info Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 12:27:32 volumio volumio[1073]: info: Discovery: Getting this device information Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:32 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:32 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:32 volumio volumio[1073]: info: Listing playlists Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 22 12:27:32 volumio volumio[1073]: info: CoreCommandRouter::volumioGetQueue Feb 22 12:27:32 volumio volumio[1073]: info: CoreStateMachine::getQueue Feb 22 12:27:32 volumio volumio[1073]: info: CorePlayQueue::getQueue Feb 22 12:27:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 22 12:27:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 12:27:33 volumio volumio[1073]: info: Received Get System Info Feb 22 12:27:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 12:27:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 12:27:33 volumio volumio[1073]: info: Discovery: Getting this device information Feb 22 12:27:33 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:33 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 12:27:35 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 12:27:35 volumio volumio[1073]: info: Received Get System Info Feb 22 12:27:35 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 12:27:35 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 12:27:35 volumio volumio[1073]: info: Discovery: Getting this device information Feb 22 12:27:35 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:35 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:35 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 12:27:36 volumio volumio[1073]: Searching plugin music_service/spop Feb 22 12:27:36 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 22 12:27:37 volumio volumio[1073]: info: All search sources collected, pushing search results Feb 22 12:27:41 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 22 12:27:43 volumio go-librespot[1502]: time="2026-02-22T12:27:43+01:00" level=debug msg="fetched chunk 11/13, size: 524288" uri="spotify:track:3Wj2bla9BKs0tcbmpq1p71" Feb 22 12:27:44 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 22 12:27:44 volumio volumio[1073]: info: In handleBrowseUri, curUri=spotify:artist:6ySxYu68zTsO5ghsThpGtS Feb 22 12:27:45 volumio volumio[1073]: info: Preload queue cleared Feb 22 12:27:45 volumio volumio[1073]: info: Preload queue cleared Feb 22 12:27:45 volumio volumio[1073]: info: Preload queue cleared Feb 22 12:27:45 volumio volumio[1073]: info: No valid Plugin REST Endpoint Feb 22 12:27:50 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 22 12:27:50 volumio volumio[1073]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 22 12:27:50 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 22 12:27:50 volumio volumio[1073]: info: Received Get System Version Feb 22 12:27:50 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 12:27:50 volumio volumio[1073]: info: Received Get System Info Feb 22 12:27:50 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 12:27:50 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 12:27:50 volumio volumio[1073]: info: Discovery: Getting this device information Feb 22 12:27:50 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Feb 22 12:27:50 volumio volumio[1073]: info: CorePlayQueue::getTrack 2 Feb 22 12:27:50 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 12:27:55 volumio go-librespot[1502]: time="2026-02-22T12:27:55+01:00" level=trace msg="received accesspoint ping" Feb 22 12:27:55 volumio go-librespot[1502]: time="2026-02-22T12:27:55+01:00" level=trace msg="received accesspoint pong ack" Feb 22 12:27:56 volumio go-librespot[1502]: time="2026-02-22T12:27:56+01:00" level=trace msg="sent dealer ping" Feb 22 12:27:56 volumio go-librespot[1502]: time="2026-02-22T12:27:56+01:00" level=trace msg="received dealer pong" Feb 22 12:27:57 volumio go-librespot[1502]: time="2026-02-22T12:27:57+01:00" level=debug msg="fetched chunk 12/13, size: 524288" uri="spotify:track:3Wj2bla9BKs0tcbmpq1p71" Feb 22 12:28:09 volumio volumio[1073]: Searching plugin music_service/spop Feb 22 12:28:09 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 22 12:28:09 volumio go-librespot[1502]: time="2026-02-22T12:28:09+01:00" level=debug msg="fetched chunk 13/13, size: 87572" uri="spotify:track:3Wj2bla9BKs0tcbmpq1p71" Feb 22 12:28:09 volumio volumio[1073]: info: All search sources collected, pushing search results Feb 22 12:28:11 volumio volumio[1073]: Searching plugin music_service/spop Feb 22 12:28:11 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 22 12:28:12 volumio volumio[1073]: info: All search sources collected, pushing search results Feb 22 12:28:17 volumio volumio[1073]: Searching plugin music_service/spop Feb 22 12:28:17 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 22 12:28:18 volumio volumio[1073]: info: All search sources collected, pushing search results Feb 22 12:28:20 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 22 12:28:20 volumio volumio[1073]: info: In handleBrowseUri, curUri=spotify:artist:0Tg55ZwA2AelP4GO4fDwxq Feb 22 12:28:21 volumio volumio[1073]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 12:28:21 volumio volumio[1073]: TypeError: Cannot read properties of undefined (reading 'url') Feb 22 12:28:21 volumio volumio[1073]: at /data/plugins/music_service/spop/index.js:2446:60 Feb 22 12:28:21 volumio volumio[1073]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 12:28:21 volumio volumio[1073]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 12:28:22 volumio sudo[3801]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 12:27' Feb 22 12:28:22 volumio sudo[3801]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"