Feb 26 15:19:21 volumio-bonfim go-librespot[24748]: time="2026-02-26T15:19:21Z" level=trace msg="sent dealer ping" Feb 26 15:19:21 volumio-bonfim go-librespot[24748]: time="2026-02-26T15:19:21Z" level=trace msg="received dealer pong" Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.569Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.100.101:46368 Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.629Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.100.101:46368 @ 0x22381e0" latency=-29.790356ms timeout=10s Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.630Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.630Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.100.101:46368 @ 0x22381e0" latency=-28.271607ms platform=PLATFORM_ANDROID version=5.260120.0 Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: Received Get System Info Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: Discovery: Getting this device information Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::volumioGetState Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.637Z level=INFO msg="emitting device name changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" name=Volumio-Bonfim Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.639Z level=INFO msg="emitting device language changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" language=en Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.642Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" timezone=Europe/Lisbon Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.644Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" available=true connected=false macAddress= ip4Address= ip6Address= Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.649Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" available=true connected=true macAddress=b8:27:eb:c3:98:41 ip4Address=192.168.100.114/24 ip6Address= ssid=DIGI-gR35 Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.649Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" setupComplete=true Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 26 15:19:44 volumio-bonfim volumio[24476]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 26 15:19:44 volumio-bonfim volumio[24476]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 26 15:19:44 volumio-bonfim volumio[24476]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 26 15:19:44 volumio-bonfim volumio[24476]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 26 15:19:44 volumio-bonfim volumio[24476]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 26 15:19:44 volumio-bonfim volumio[24476]: {"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 26 15:19:44 volumio-bonfim volumio[24476]: amixer -c 5 info | grep "Devialet USB Audio 2.0" Feb 26 15:19:44 volumio-bonfim volumio[24476]: Invalid card number '5'. Feb 26 15:19:44 volumio-bonfim volumio[24476]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Feb 26 15:19:44 volumio-bonfim volumio[24476]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 26 15:19:44 volumio-bonfim volumio[24476]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\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 26 15:19:44 volumio-bonfim volumio[24476]: amixer -c 2 info | grep "vc4-hdmi" Feb 26 15:19:44 volumio-bonfim volumio[24476]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.846Z level=WARN msg="active output device not found in available devices" component=volumio/socket activeDeviceId=5 Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.846Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" selectedOutputId=5 Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: Received Get System Info Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: Discovery: Getting this device information Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::volumioGetState Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.873Z level=INFO msg="emitting software info changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" currentVersion=4.096 latestVersion=4.096 Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.873Z level=INFO msg="emitting software update progress event" component=server peer="192.168.100.101:46368 @ 0x22381e0" status=UPDATE_STATUS_NONE progress=0 Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.874Z level=INFO msg="emitting user changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" userId=bvZ9X7ZJnphg6zsTDQRgJOfCvku2 Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.874Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" providers=9 Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.874Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" plugins=61 Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::volumioGetState Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.879Z level=INFO msg="emitting player state changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" state=STATUS_PAUSED positionMs=16534875 volume=100 Feb 26 15:19:44 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:44.879Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.100.101:46368 @ 0x22381e0" id=https://streams.radiomast.io/818607ce-8815-42fb-8dfc-344234a0ff83 title="Abbey Lincoln - n013c050M People In Me" Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: Discovery: Getting this device information Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::volumioGetState Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 15:19:44 volumio-bonfim volumio[24476]: verbose: New Socket.io Connection to 192.168.100.114:3000 from 192.168.100.101 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 15:19:44 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 15:19:45 volumio-bonfim volumio5-onboarding[5364]: time=2026-02-26T15:19:45.581Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.100.101:46368 @ 0x22381e0" latency=-27.453407ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 26 15:19:46 volumio-bonfim sudo[21856]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 15:19:46 volumio-bonfim sudo[21856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 15:19:46 volumio-bonfim sudo[21856]: pam_unix(sudo:session): session closed for user root Feb 26 15:19:46 volumio-bonfim sudo[21858]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 15:19:46 volumio-bonfim sudo[21858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 15:19:46 volumio-bonfim sudo[21858]: pam_unix(sudo:session): session closed for user root Feb 26 15:19:46 volumio-bonfim volumio[24476]: verbose: New Socket.io Connection to 192.168.100.114 from 192.168.100.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S921B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::volumioGetState Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 15:19:46 volumio-bonfim sudo[21863]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 15:19:46 volumio-bonfim sudo[21863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 15:19:46 volumio-bonfim sudo[21863]: pam_unix(sudo:session): session closed for user root Feb 26 15:19:46 volumio-bonfim sudo[21865]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 15:19:46 volumio-bonfim sudo[21865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 15:19:46 volumio-bonfim sudo[21865]: pam_unix(sudo:session): session closed for user root Feb 26 15:19:46 volumio-bonfim volumio[24476]: verbose: New Socket.io Connection to 192.168.100.114 from 192.168.100.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S921B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::volumioGetState Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: Listing playlists Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 26 15:19:46 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 15:19:49 volumio-bonfim volumio[24476]: info: CoreCommandRouter::volumioGetState Feb 26 15:19:49 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 26 15:19:49 volumio-bonfim volumio[24476]: info: CoreCommandRouter::volumioPlay Feb 26 15:19:49 volumio-bonfim volumio[24476]: info: CoreStateMachine::play index undefined Feb 26 15:19:49 volumio-bonfim volumio[24476]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 15:19:49 volumio-bonfim volumio[24476]: info: CorePlayQueue::getTrack 0 Feb 26 15:19:51 volumio-bonfim go-librespot[24748]: time="2026-02-26T15:19:51Z" level=trace msg="sent dealer ping" Feb 26 15:19:51 volumio-bonfim go-librespot[24748]: time="2026-02-26T15:19:51Z" level=trace msg="received dealer pong" Feb 26 15:20:02 volumio-bonfim volumio[24476]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Feb 26 15:20:02 volumio-bonfim volumio[24476]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Feb 26 15:20:02 volumio-bonfim volumio[24476]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 15:20:02 volumio-bonfim volumio[24476]: TypeError: Cannot read properties of undefined (reading 'then') Feb 26 15:20:02 volumio-bonfim volumio[24476]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Feb 26 15:20:02 volumio-bonfim volumio[24476]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Feb 26 15:20:02 volumio-bonfim volumio[24476]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Feb 26 15:20:02 volumio-bonfim volumio[24476]: at Socket.emit (node:events:514:28) Feb 26 15:20:02 volumio-bonfim volumio[24476]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 26 15:20:02 volumio-bonfim volumio[24476]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 26 15:20:02 volumio-bonfim volumio[24476]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 15:20:03 volumio-bonfim sudo[21911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 15:19' Feb 26 15:20:03 volumio-bonfim sudo[21911]: 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"