May 30 10:16:01 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:05 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:08 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:08.985-06:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.135:52657 May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:09 v-parlor volumio[1221]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.135 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: 12 May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetVisibleSources May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:09 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 10:16:09 v-parlor volumio[1221]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 30 10:16:09 v-parlor volumio[1221]: info: Received Get System Info May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 10:16:09 v-parlor volumio[1221]: info: Discovery: Getting this device information May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:09 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:09 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:09 v-parlor volumio[1221]: info: Listing playlists May 30 10:16:09 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 30 10:16:11 v-parlor volumio[1221]: info: MPD Database updated - AlbumList cache refreshed May 30 10:16:11 v-parlor volumio[1221]: info: May 30 10:16:11 v-parlor volumio[1221]: ---------------------------- MPD announces state update: database May 30 10:16:11 v-parlor volumio[1221]: verbose: ControllerMpd::sendMpdCommand status May 30 10:16:11 v-parlor volumio[1221]: info: May 30 10:16:11 v-parlor volumio[1221]: ---------------------------- MPD announces state update: update May 30 10:16:11 v-parlor volumio[1221]: info: ControllerMpd::getState May 30 10:16:11 v-parlor volumio[1221]: verbose: ControllerMpd::sendMpdCommand status May 30 10:16:11 v-parlor volumio[1221]: info: ControllerMpd::getState May 30 10:16:11 v-parlor volumio[1221]: verbose: ControllerMpd::sendMpdCommand status May 30 10:16:13 v-parlor volumio[1221]: info: sendMpdCommand status took 1897 milliseconds May 30 10:16:13 v-parlor volumio[1221]: info: sendMpdCommand status took 1897 milliseconds May 30 10:16:13 v-parlor volumio[1221]: info: sendMpdCommand status took 1897 milliseconds May 30 10:16:13 v-parlor volumio[1221]: verbose: ControllerMpd::parseState May 30 10:16:13 v-parlor volumio[1221]: info: Command Router : Notfying DB Updatetrue May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::Close All Modals sent May 30 10:16:13 v-parlor volumio[1221]: verbose: ControllerMpd::parseState May 30 10:16:13 v-parlor volumio[1221]: verbose: ControllerMpd::parseState May 30 10:16:13 v-parlor volumio[1221]: info: ControllerMpd::pushState May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::servicePushState May 30 10:16:13 v-parlor volumio[1221]: info: CoreStateMachine::pushState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioPushState May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output update for this device May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 30 10:16:13 v-parlor volumio[1221]: verbose: CURRENT POSITION 0 May 30 10:16:13 v-parlor volumio[1221]: info: CoreStateMachine::syncState stateService stop May 30 10:16:13 v-parlor volumio[1221]: info: CoreStateMachine::syncState currentStatus stop May 30 10:16:13 v-parlor volumio[1221]: info: CoreStateMachine::pushState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioPushState May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output update for this device May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: No code May 30 10:16:13 v-parlor volumio[1221]: info: CoreStateMachine::pushState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioPushState May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output update for this device May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: ControllerMpd::pushState May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::servicePushState May 30 10:16:13 v-parlor volumio[1221]: info: CoreStateMachine::pushState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioPushState May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output update for this device May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 30 10:16:13 v-parlor volumio[1221]: verbose: CURRENT POSITION 0 May 30 10:16:13 v-parlor volumio[1221]: info: CoreStateMachine::syncState stateService stop May 30 10:16:13 v-parlor volumio[1221]: info: CoreStateMachine::syncState currentStatus stop May 30 10:16:13 v-parlor volumio[1221]: info: CoreStateMachine::pushState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioPushState May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output update for this device May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: No code May 30 10:16:13 v-parlor volumio[1221]: info: CoreStateMachine::pushState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioPushState May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output update for this device May 30 10:16:13 v-parlor volumio[1221]: info: MRS: Pushing multiroomSync output May 30 10:16:13 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:13 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.047-06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" state=STATUS_STOPPED positionMs=0 volume=86 May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.047-06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" id= title= May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.049-06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" state=STATUS_STOPPED positionMs=0 volume=86 May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.050-06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" id= title= May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.053-06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" state=STATUS_STOPPED positionMs=0 volume=86 May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.053-06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" id= title= May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.056-06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" state=STATUS_STOPPED positionMs=0 volume=86 May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.057-06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" id= title= May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.058-06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" state=STATUS_STOPPED positionMs=0 volume=86 May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.058-06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" id= title= May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.061-06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" state=STATUS_STOPPED positionMs=0 volume=86 May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.062-06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.135:52657 @ 0x1b904e0" id= title= May 30 10:16:14 v-parlor volumio[1221]: info: ------------------------------ 2256ms May 30 10:16:14 v-parlor volumio[1221]: info: ------------------------------ 2257ms May 30 10:16:14 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:14 v-parlor volumio[1221]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 30 10:16:14 v-parlor volumio[1221]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 30 10:16:14 v-parlor volumio[1221]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 30 10:16:14 v-parlor volumio[1221]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 30 10:16:14 v-parlor volumio[1221]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 30 10:16:14 v-parlor volumio[1221]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 May 30 10:16:14 v-parlor bluealsa[1040]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_69_E5_DE_8A_01_28, ...) May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.464-06:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.135:52657 May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.464-06:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.135:52657 May 30 10:16:14 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:14.478-06:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.135:52668 May 30 10:16:15 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:15.984-06:00 level=INFO msg="new address was allocated" component=ble/conn old=7 new=8 May 30 10:16:17 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.411-06:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.135:52668 @ 0x1b90b10" latency=-30.532361ms timeout=10s May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.411-06:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.412-06:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.135:52668 @ 0x1b90b10" latency=-28.071982ms platform=PLATFORM_IOS version=5.260413.0 May 30 10:16:20 v-parlor volumio[1221]: info: Received Get System Info May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 10:16:20 v-parlor volumio[1221]: info: Discovery: Getting this device information May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:20 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.417-06:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" name="V Parlor" May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.421-06:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" language=en May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.423-06:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" timezone=America/Denver May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.425-06:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" available=true connected=false macAddress= ip4Address= ip6Address= May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.442-06:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" available=true connected=true macAddress=88:a2:9e:6f:d5:dc ip4Address=192.168.1.130/24 ip6Address= ssid=TheForce May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.442-06:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" setupComplete=true May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 30 10:16:20 v-parlor volumio[1221]: amixer -c 0 info | grep "bcm2835 ALSA" May 30 10:16:20 v-parlor volumio[1221]: amixer -c 0 info | grep "bcm2835 ALSA" May 30 10:16:20 v-parlor volumio[1221]: amixer -c 1 info | grep "bcm2835 Headphones" May 30 10:16:20 v-parlor volumio[1221]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' May 30 10:16:20 v-parlor volumio[1221]: amixer -c 2 info | grep "vc4-hdmi-0" May 30 10:16:20 v-parlor volumio[1221]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' May 30 10:16:20 v-parlor volumio[1221]: amixer -c 3 info | grep "vc4-hdmi-1" May 30 10:16:20 v-parlor volumio[1221]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.577-06:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" selectedOutputId=0 May 30 10:16:20 v-parlor volumio[1221]: info: Received Get System Info May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 10:16:20 v-parlor volumio[1221]: info: Discovery: Getting this device information May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:20 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.626-06:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" currentVersion=4.119 latestVersion=4.119 May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.627-06:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" status=UPDATE_STATUS_NONE progress=0 May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.627-06:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" userId=rZLgCbIVVKOtLOkcMQO4GoYHr4B3 May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.627-06:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" providers=9 May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.628-06:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" plugins=67 May 30 10:16:20 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:20 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.632-06:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" state=STATUS_STOPPED positionMs=0 volume=86 May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.632-06:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.135:52668 @ 0x1b90b10" id= title= May 30 10:16:20 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:20.686-06:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.135:52668 @ 0x1b90b10" latency=-35.707302ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 30 10:16:20 v-parlor sudo[8148]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 10:16:20 v-parlor sudo[8148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 10:16:20 v-parlor sudo[8150]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 10:16:20 v-parlor sudo[8150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 10:16:20 v-parlor sudo[8148]: pam_unix(sudo:session): session closed for user root May 30 10:16:20 v-parlor sudo[8150]: pam_unix(sudo:session): session closed for user root May 30 10:16:20 v-parlor volumio[1221]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.135 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: 12 May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:21 v-parlor sudo[8155]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 30 10:16:21 v-parlor sudo[8155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 10:16:21 v-parlor sudo[8155]: pam_unix(sudo:session): session closed for user root May 30 10:16:21 v-parlor sudo[8156]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 30 10:16:21 v-parlor sudo[8156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 30 10:16:21 v-parlor sudo[8156]: pam_unix(sudo:session): session closed for user root May 30 10:16:21 v-parlor volumio[1221]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.135 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: 12 May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetVisibleSources May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:21 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 10:16:21 v-parlor volumio[1221]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 30 10:16:21 v-parlor volumio[1221]: info: Received Get System Info May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 10:16:21 v-parlor volumio[1221]: info: Discovery: Getting this device information May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:21 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:21 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:21 v-parlor volumio[1221]: info: Listing playlists May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 30 10:16:21 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 30 10:16:22 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 10:16:23 v-parlor volumio[1221]: info: Received Get System Info May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 10:16:23 v-parlor volumio[1221]: info: Discovery: Getting this device information May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:23 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 30 10:16:23 v-parlor volumio[1221]: info: Received Get System Info May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 10:16:23 v-parlor volumio[1221]: info: Discovery: Getting this device information May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:23 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:23 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 30 10:16:23 v-parlor volumio[1221]: info: CoreCommandRouter::volumioPlay May 30 10:16:23 v-parlor volumio[1221]: info: CoreStateMachine::play index undefined May 30 10:16:23 v-parlor volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 10:16:23 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:25 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:29 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:33 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:37 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:39 v-parlor bluealsa[1040]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_6D_4E_19_FA_67_C1, ...) May 30 10:16:41 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:45 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:48 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 30 10:16:50 v-parlor volumio[1221]: info: CoreCommandRouter::volumioGetState May 30 10:16:50 v-parlor volumio[1221]: info: CorePlayQueue::getTrack 0 May 30 10:16:52 v-parlor volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 30 10:16:52 v-parlor volumio[1221]: info: CURURI: artists:// May 30 10:16:52 v-parlor volumio[1221]: info: Preload queue cleared May 30 10:16:58 v-parlor volumio5-onboarding[1884]: time=2026-05-30T10:16:58.459-06:00 level=INFO msg="new address was allocated" component=ble/conn old=8 new=9 May 30 10:17:20 v-parlor volumio[1221]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 10:17:20 v-parlor volumio[1221]: TypeError: Cannot read properties of null (reading 'slice') May 30 10:17:20 v-parlor volumio[1221]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) May 30 10:17:20 v-parlor volumio[1221]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) May 30 10:17:20 v-parlor volumio[1221]: at Object.onceWrapper (node:events:629:26) May 30 10:17:20 v-parlor volumio[1221]: at ClientRequest.emit (node:events:514:28) May 30 10:17:20 v-parlor volumio[1221]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) May 30 10:17:20 v-parlor volumio[1221]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) May 30 10:17:20 v-parlor volumio[1221]: at TLSSocket.socketOnData (node:_http_client:535:22) May 30 10:17:20 v-parlor volumio[1221]: at TLSSocket.emit (node:events:514:28) May 30 10:17:20 v-parlor volumio[1221]: at addChunk (node:internal/streams/readable:343:12) May 30 10:17:20 v-parlor volumio[1221]: at readableAddChunk (node:internal/streams/readable:316:9) May 30 10:17:20 v-parlor volumio[1221]: at Readable.push (node:internal/streams/readable:253:10) May 30 10:17:20 v-parlor volumio[1221]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) May 30 10:17:20 v-parlor volumio[1221]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 10:17:22 v-parlor sudo[8268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 10:16' May 30 10:17:22 v-parlor sudo[8268]: 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"