Feb 11 21:59:23 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:25 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:25 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:25 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:25 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:25 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:25 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:26 volumio volumio[1487]: info: CorePlayQueue::getTrack 0 Feb 11 21:59:26 volumio volumio[1487]: info: CorePlayQueue::getTrack 1 Feb 11 21:59:26 volumio volumio[1487]: info: Prefetching next song Feb 11 21:59:26 volumio volumio[1487]: info: Doing Prefetch in UPNP Feb 11 21:59:26 volumio volumio[1487]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/01*20Alone*20Together.flac" Feb 11 21:59:26 volumio volumio[1487]: info: Feb 11 21:59:26 volumio volumio[1487]: ---------------------------- MPD announces system playlist update Feb 11 21:59:26 volumio volumio[1487]: info: Ignoring MPD Status Update Feb 11 21:59:26 volumio volumio[1487]: info: sendMpdCommand add "http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/01*20Alone*20Together.flac" took 1 milliseconds Feb 11 21:59:26 volumio volumio[1487]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 11 21:59:26 volumio volumio[1487]: info: Feb 11 21:59:26 volumio volumio[1487]: ---------------------------- MPD announces state update: options Feb 11 21:59:26 volumio volumio[1487]: info: ------------------------------ 1ms Feb 11 21:59:26 volumio volumio[1487]: info: sendMpdCommand consume 1 took 0 milliseconds Feb 11 21:59:26 volumio volumio[1487]: info: ControllerMpd::getState Feb 11 21:59:26 volumio volumio[1487]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:59:26 volumio volumio[1487]: info: sendMpdCommand status took 1 milliseconds Feb 11 21:59:26 volumio volumio[1487]: verbose: ControllerMpd::parseState Feb 11 21:59:26 volumio volumio[1487]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:59:26 volumio volumio[1487]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 11 21:59:26 volumio volumio[1487]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:59:26 volumio volumio[1487]: info: ControllerMpd::pushState Feb 11 21:59:26 volumio volumio[1487]: info: CoreCommandRouter::servicePushState Feb 11 21:59:26 volumio volumio[1487]: info: CorePlayQueue::getTrack 0 Feb 11 21:59:26 volumio volumio[1487]: verbose: STATE SERVICE {"status":"play","position":0,"seek":563302,"duration":568,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1316 Kbps","isStreaming":false,"title":"50 Ways To Leave Your Lover(Live)","artist":"Dr. Lonnie Smith","album":"All In My Mind","uri":"http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/03*2050*20Ways*20To*20Leave*20Your*20Lover(Live).flac","trackType":"flac"} Feb 11 21:59:26 volumio volumio[1487]: verbose: CURRENT POSITION 0 Feb 11 21:59:26 volumio volumio[1487]: info: CoreStateMachine::syncState stateService play Feb 11 21:59:26 volumio volumio[1487]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:59:26 volumio volumio[1487]: info: Received an update from plugin. extracting info from payload Feb 11 21:59:26 volumio volumio[1487]: info: CoreStateMachine::pushState Feb 11 21:59:26 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:59:26 volumio volumio[1487]: info: CoreCommandRouter::volumioPushState Feb 11 21:59:26 volumio volumio[1487]: info: MRS: Pushing multiroomSync output update for this device Feb 11 21:59:26 volumio volumio[1487]: info: MRS: Pushing multiroomSync output Feb 11 21:59:26 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:26 volumio volumio[1487]: info: CoreStateMachine::pushState Feb 11 21:59:26 volumio volumio[1487]: info: CoreCommandRouter::volumioPushState Feb 11 21:59:26 volumio volumio[1487]: info: MRS: Pushing multiroomSync output update for this device Feb 11 21:59:26 volumio volumio[1487]: info: MRS: Pushing multiroomSync output Feb 11 21:59:26 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:26 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:26.051+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" state=STATUS_PLAYING positionMs=562147 volume=100 Feb 11 21:59:26 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:26.051+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" state=STATUS_PLAYING positionMs=562147 volume=100 Feb 11 21:59:26 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:26.052+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" id=http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/03*2050*20Ways*20To*20Leave*20Your*20Lover(Live).flac title="50 Ways To Leave Your Lover(Live)" Feb 11 21:59:26 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:26.054+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" id=http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/03*2050*20Ways*20To*20Leave*20Your*20Lover(Live).flac title="50 Ways To Leave Your Lover(Live)" Feb 11 21:59:26 volumio volumio[1487]: info: ------------------------------ 13ms Feb 11 21:59:26 volumio volumio[1487]: info: CoreCommandRouter::volumioGetQueue Feb 11 21:59:26 volumio volumio[1487]: info: CoreStateMachine::getQueue Feb 11 21:59:26 volumio volumio[1487]: info: CorePlayQueue::getQueue Feb 11 21:59:30 volumio volumio[1487]: info: CoreCommandRouter::volumioGetQueue Feb 11 21:59:30 volumio volumio[1487]: info: CoreStateMachine::getQueue Feb 11 21:59:30 volumio volumio[1487]: info: CorePlayQueue::getQueue Feb 11 21:59:32 volumio volumio[1487]: info: Feb 11 21:59:32 volumio volumio[1487]: ---------------------------- MPD announces system playlist update Feb 11 21:59:32 volumio volumio[1487]: info: Ignoring MPD Status Update Feb 11 21:59:32 volumio volumio[1487]: info: Feb 11 21:59:32 volumio volumio[1487]: ---------------------------- MPD announces state update: player Feb 11 21:59:32 volumio volumio[1487]: info: ControllerMpd::getState Feb 11 21:59:32 volumio volumio[1487]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:59:32 volumio volumio[1487]: info: ------------------------------ 7ms Feb 11 21:59:32 volumio volumio[1487]: info: sendMpdCommand status took 7 milliseconds Feb 11 21:59:32 volumio volumio[1487]: verbose: ControllerMpd::parseState Feb 11 21:59:32 volumio volumio[1487]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:59:32 volumio volumio[1487]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 11 21:59:32 volumio volumio[1487]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:59:32 volumio volumio[1487]: info: ControllerMpd::pushState Feb 11 21:59:32 volumio volumio[1487]: info: CoreCommandRouter::servicePushState Feb 11 21:59:32 volumio volumio[1487]: info: CorePlayQueue::getTrack 0 Feb 11 21:59:32 volumio volumio[1487]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1802,"duration":825,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1372 Kbps","isStreaming":false,"title":"Alone Together","artist":"Lee Konitz, Brad Mehldau, Charlie Haden","album":"Alone Together","uri":"http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/01*20Alone*20Together.flac","trackType":"flac"} Feb 11 21:59:32 volumio volumio[1487]: verbose: CURRENT POSITION 0 Feb 11 21:59:32 volumio volumio[1487]: info: CoreStateMachine::syncState stateService play Feb 11 21:59:32 volumio volumio[1487]: info: CoreStateMachine::syncState currentStatus play Feb 11 21:59:32 volumio volumio[1487]: info: Received an update from plugin. extracting info from payload Feb 11 21:59:32 volumio volumio[1487]: info: CoreStateMachine::pushState Feb 11 21:59:32 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:59:32 volumio volumio[1487]: info: CoreCommandRouter::volumioPushState Feb 11 21:59:32 volumio volumio[1487]: info: MRS: Pushing multiroomSync output update for this device Feb 11 21:59:32 volumio volumio[1487]: info: MRS: Pushing multiroomSync output Feb 11 21:59:32 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:32 volumio volumio[1487]: info: CoreStateMachine::pushState Feb 11 21:59:32 volumio volumio[1487]: info: CoreCommandRouter::volumioPushState Feb 11 21:59:32 volumio volumio[1487]: info: MRS: Pushing multiroomSync output update for this device Feb 11 21:59:32 volumio volumio[1487]: info: MRS: Pushing multiroomSync output Feb 11 21:59:32 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:32 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:32.264+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" state=STATUS_PLAYING positionMs=568340 volume=100 Feb 11 21:59:32 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:32.264+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" id=http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/01*20Alone*20Together.flac title="Alone Together" Feb 11 21:59:32 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:32.265+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" state=STATUS_PLAYING positionMs=568340 volume=100 Feb 11 21:59:32 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:32.265+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" id=http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/01*20Alone*20Together.flac title="Alone Together" Feb 11 21:59:32 volumio volumio[1487]: info: ------------------------------ 27ms Feb 11 21:59:32 volumio volumio[1487]: info: CoreCommandRouter::volumioGetQueue Feb 11 21:59:32 volumio volumio[1487]: info: CoreStateMachine::getQueue Feb 11 21:59:32 volumio volumio[1487]: info: CorePlayQueue::getQueue Feb 11 21:59:34 volumio volumio[1487]: info: CoreCommandRouter::volumioGetQueue Feb 11 21:59:34 volumio volumio[1487]: info: CoreStateMachine::getQueue Feb 11 21:59:34 volumio volumio[1487]: info: CorePlayQueue::getQueue Feb 11 21:59:35 volumio volumio[1487]: error: error Feb 11 21:59:35 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:35 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:35 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:35 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:35 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:35 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:35 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:35 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:35 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:35 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:35 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:35 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.600+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.66:58294 @ 0xc0003bcf30" latency=31.841142ms platform=PLATFORM_IOS version=5.260120.0 Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.601+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.66:58294 @ 0xc0003bcf30" latency=30.548396ms timeout=10s Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.601+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" Feb 11 21:59:45 volumio volumio[1487]: info: Received Get System Info Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 21:59:45 volumio volumio[1487]: info: Discovery: Getting this device information Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.604+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" name=Volumio Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.604+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" language=it Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.606+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" timezone=Europe/Rome Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.606+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" available=true connected=true macAddress=20:7b:d2:a1:d7:ed ip4Address=192.168.1.170/24 ip6Address= Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.607+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.607+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" setupComplete=true Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 21:59:45 volumio volumio[1487]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Feb 11 21:59:45 volumio volumio[1487]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 11 21:59:45 volumio volumio[1487]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 11 21:59:45 volumio volumio[1487]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 11 21:59:45 volumio volumio[1487]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 11 21:59:45 volumio volumio[1487]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 11 21:59:45 volumio volumio[1487]: amixer -c 5 info | grep "SENNHEISER HDV 820" Feb 11 21:59:45 volumio volumio[1487]: Card sysdefault:5 'S820'/'SENNHEISER SENNHEISER HDV 820 at usb-0000:00:15.0-3.2, high speed' Feb 11 21:59:45 volumio volumio[1487]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 11 21:59:45 volumio volumio[1487]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 11 21:59:45 volumio volumio[1487]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Feb 11 21:59:45 volumio volumio[1487]: amixer -c 5 info | grep "SENNHEISER HDV 820" Feb 11 21:59:45 volumio volumio[1487]: Card sysdefault:5 'S820'/'SENNHEISER SENNHEISER HDV 820 at usb-0000:00:15.0-3.2, high speed' Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.685+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" selectedOutputId=5 Feb 11 21:59:45 volumio volumio[1487]: info: Received Get System Info Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 21:59:45 volumio volumio[1487]: info: Discovery: Getting this device information Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.699+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" currentVersion=4.096 latestVersion=4.096 Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.699+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" status=UPDATE_STATUS_NONE progress=0 Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.699+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" userId= Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.699+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" providers=3 Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.699+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" plugins=44 Feb 11 21:59:45 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.701+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" state=STATUS_PLAYING positionMs=581750 volume=100 Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.701+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" id=http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/01*20Alone*20Together.flac title="Alone Together" Feb 11 21:59:45 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:45.750+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.66:58294 @ 0xc0003bcf30" latency=20.404702ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 11 21:59:46 volumio sudo[18146]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 21:59:46 volumio sudo[18145]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 21:59:46 volumio sudo[18146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:59:46 volumio sudo[18145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:59:46 volumio sudo[18146]: pam_unix(sudo:session): session closed for user root Feb 11 21:59:46 volumio sudo[18145]: pam_unix(sudo:session): session closed for user root Feb 11 21:59:46 volumio volumio[1487]: verbose: New Socket.io Connection to 192.168.1.170 from 192.168.1.66 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 10 Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 21:59:46 volumio sudo[18150]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 21:59:46 volumio sudo[18150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:59:46 volumio sudo[18150]: pam_unix(sudo:session): session closed for user root Feb 11 21:59:46 volumio sudo[18152]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 21:59:46 volumio sudo[18152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 21:59:46 volumio sudo[18152]: pam_unix(sudo:session): session closed for user root Feb 11 21:59:46 volumio volumio[1487]: verbose: New Socket.io Connection to 192.168.1.170 from 192.168.1.66 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 10 Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 21:59:46 volumio volumio[1487]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 11 21:59:46 volumio volumio[1487]: info: Received Get System Info Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 21:59:46 volumio volumio[1487]: info: Discovery: Getting this device information Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:46 volumio volumio[1487]: info: Listing playlists Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 11 21:59:46 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 21:59:47 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:48 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 11 21:59:48 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 21:59:48 volumio volumio[1487]: info: Received Get System Info Feb 11 21:59:48 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 21:59:48 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 21:59:48 volumio volumio[1487]: info: Discovery: Getting this device information Feb 11 21:59:48 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:48 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 21:59:49 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:49 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:49 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:49 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:49 volumio volumio[1487]: info: Executing endpoint metavolumio Feb 11 21:59:49 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 11 21:59:49 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 21:59:49 volumio volumio[1487]: info: Received Get System Info Feb 11 21:59:49 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 21:59:49 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 21:59:49 volumio volumio[1487]: info: Discovery: Getting this device information Feb 11 21:59:49 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:49 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 21:59:51 volumio volumio[1487]: info: CoreCommandRouter::volumioPause Feb 11 21:59:51 volumio volumio[1487]: info: CoreStateMachine::pause Feb 11 21:59:51 volumio volumio[1487]: info: CoreStateMachine::stPlaybackTimer Feb 11 21:59:51 volumio volumio[1487]: info: CoreStateMachine::servicePause Feb 11 21:59:51 volumio volumio[1487]: info: CorePlayQueue::getTrack 0 Feb 11 21:59:51 volumio volumio[1487]: info: CoreCommandRouter::servicePause Feb 11 21:59:51 volumio volumio[1487]: info: [1770843591045] ControllerUPNPBrowser::pause Feb 11 21:59:51 volumio volumio[1487]: verbose: ControllerMpd::sendMpdCommand pause Feb 11 21:59:51 volumio volumio[1487]: info: Feb 11 21:59:51 volumio volumio[1487]: ---------------------------- MPD announces state update: player Feb 11 21:59:51 volumio volumio[1487]: info: sendMpdCommand pause took 1 milliseconds Feb 11 21:59:51 volumio volumio[1487]: info: ControllerMpd::getState Feb 11 21:59:51 volumio volumio[1487]: verbose: ControllerMpd::sendMpdCommand status Feb 11 21:59:51 volumio volumio[1487]: info: sendMpdCommand status took 0 milliseconds Feb 11 21:59:51 volumio volumio[1487]: verbose: ControllerMpd::parseState Feb 11 21:59:51 volumio volumio[1487]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 21:59:51 volumio volumio[1487]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 11 21:59:51 volumio volumio[1487]: verbose: ControllerMpd::parseTrackInfo Feb 11 21:59:51 volumio volumio[1487]: info: ControllerMpd::pushState Feb 11 21:59:51 volumio volumio[1487]: info: CoreCommandRouter::servicePushState Feb 11 21:59:51 volumio volumio[1487]: info: CorePlayQueue::getTrack 0 Feb 11 21:59:51 volumio volumio[1487]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":20553,"duration":825,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1462 Kbps","isStreaming":false,"title":"Alone Together","artist":"Lee Konitz, Brad Mehldau, Charlie Haden","album":"Alone Together","uri":"http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/01*20Alone*20Together.flac","trackType":"flac"} Feb 11 21:59:51 volumio volumio[1487]: verbose: CURRENT POSITION 0 Feb 11 21:59:51 volumio volumio[1487]: info: CoreStateMachine::syncState stateService pause Feb 11 21:59:51 volumio volumio[1487]: info: CoreStateMachine::syncState currentStatus pause Feb 11 21:59:51 volumio volumio[1487]: info: CoreStateMachine::pushState Feb 11 21:59:51 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 21:59:51 volumio volumio[1487]: info: CoreCommandRouter::volumioPushState Feb 11 21:59:51 volumio volumio[1487]: info: MRS: Pushing multiroomSync output update for this device Feb 11 21:59:51 volumio volumio[1487]: info: MRS: Pushing multiroomSync output Feb 11 21:59:51 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 21:59:51 volumio volumio[1487]: info: CoreStateMachine::stPlaybackTimer Feb 11 21:59:51 volumio volumio[1487]: info: ------------------------------ 8ms Feb 11 21:59:51 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:51.056+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" state=STATUS_PAUSED positionMs=587007 volume=100 Feb 11 21:59:51 volumio volumio5-onboarding[2256]: time=2026-02-11T21:59:51.056+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.66:58294 @ 0xc0003bcf30" id=http://192.168.1.238:9790/minimserver/*/Lroot/TIDAL*20MASTER*2007.02.2026/Blue*20Note_*20Rebirth/01*20Alone*20Together.flac title="Alone Together" Feb 11 21:59:51 volumio volumio[1487]: info: CoreCommandRouter::volumioGetQueue Feb 11 21:59:51 volumio volumio[1487]: info: CoreStateMachine::getQueue Feb 11 21:59:51 volumio volumio[1487]: info: CorePlayQueue::getQueue Feb 11 22:00:00 volumio volumio[1487]: error: Failed request for metavolumio API Feb 11 22:00:00 volumio volumio[1487]: error: Failed request for metavolumio API Feb 11 22:00:00 volumio volumio[1487]: error: Failed request for metavolumio API Feb 11 22:00:00 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 11 22:00:04 volumio volumio[1487]: info: Received Get System Version Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 22:00:04 volumio volumio[1487]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 11 22:00:04 volumio volumio[1487]: info: Received Get System Version Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 22:00:04 volumio volumio[1487]: info: Received Get System Info Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 22:00:04 volumio volumio[1487]: info: Discovery: Getting this device information Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::volumioGetState Feb 11 22:00:04 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 22:00:16 volumio volumio[1487]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Feb 11 22:00:16 volumio volumio[1487]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Feb 11 22:00:16 volumio volumio[1487]: info: Setting active UI to: {"uiPrettyName":"Contemporary","uiName":"contemporary","uiPath":"/volumio/http/www3"} Feb 11 22:00:16 volumio volumio[1487]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 22:00:16 volumio volumio[1487]: Error: ENOSPC: no space left on device, write Feb 11 22:00:16 volumio volumio[1487]: at Object.writeSync (node:fs:952:3) Feb 11 22:00:16 volumio volumio[1487]: at Object.writeFileSync (node:fs:2340:26) Feb 11 22:00:16 volumio volumio[1487]: at Object.writeFileSync (/volumio/node_modules/jsonfile/index.js:116:13) Feb 11 22:00:16 volumio volumio[1487]: at /volumio/app/plugins/miscellanea/appearance/index.js:483:12 Feb 11 22:00:16 volumio volumio[1487]: at callback (/volumio/node_modules/graceful-fs/polyfills.js:306:20) Feb 11 22:00:16 volumio volumio[1487]: at FSReqCallback.oncomplete (node:fs:200:21) { Feb 11 22:00:16 volumio volumio[1487]: errno: -28, Feb 11 22:00:16 volumio volumio[1487]: syscall: 'write', Feb 11 22:00:16 volumio volumio[1487]: code: 'ENOSPC' Feb 11 22:00:16 volumio volumio[1487]: } Feb 11 22:00:16 volumio volumio[1487]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 22:00:16 volumio sudo[18258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 21:59' Feb 11 22:00:16 volumio sudo[18258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"