Oct 16 09:10:07 volumio nmbd[956]: [2026/10/16 09:10:07.805681, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 16 09:10:07 volumio nmbd[956]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.91 for name WORKGROUP<1d>. Oct 16 09:10:07 volumio nmbd[956]: This response was from IP 192.168.1.152, reporting an IP address of 192.168.1.152. Oct 16 09:10:13 volumio volumio-remote-updater[657]: [2026-10-16 09:10:13] [connect] Successful connection Oct 16 09:10:28 volumio volumio-remote-updater[657]: [2026-10-16 09:10:28] [connect] Successful connection Oct 16 09:10:43 volumio volumio-remote-updater[657]: [2026-10-16 09:10:43] [connect] Successful connection Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.453+01:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.475+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=21.360973ms Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.517+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=http://pushupdates.volumio.org duration=57.136518ms Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.519+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=65.180939ms Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.601+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=https://www.googleapis.com duration=144.134627ms Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.621+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=https://securetoken.googleapis.com duration=168.068359ms Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.639+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=https://google.com duration=184.381107ms Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.658+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=http://plugins.volumio.org duration=201.025886ms Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.675+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=https://functions.volumio.cloud duration=220.103006ms Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.677+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=https://functions.volumio.cloud duration=217.968007ms Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.700+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=http://cddb.volumio.org duration=241.270385ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 09:10:43 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:43.720+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=https://database.volumio.cloud duration=262.101879ms Oct 16 09:10:44 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:44.749+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=1.294759462s Oct 16 09:10:45 volumio volumio5-onboarding[1555]: time=2026-10-16T09:10:45.019+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=53.769991ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=1.562919669s Oct 16 09:10:58 volumio volumio-remote-updater[657]: [2026-10-16 09:10:58] [connect] Successful connection Oct 16 09:11:09 volumio volumio[1109]: <--- Last few GCs ---> Oct 16 09:11:09 volumio volumio[1109]: [1109:0x61df6e0] 42234485 ms: Mark-Compact 212.7 (222.2) -> 210.7 (222.4) MB, 1934.51 / 0.00 ms (average mu = 0.225, current mu = 0.150) allocation failure; scavenge might not succeed Oct 16 09:11:09 volumio volumio[1109]: [1109:0x61df6e0] 42236685 ms: Mark-Compact 212.8 (222.4) -> 210.8 (222.7) MB, 2029.68 / 0.00 ms (average mu = 0.158, current mu = 0.077) allocation failure; scavenge might not succeed Oct 16 09:11:09 volumio volumio[1109]: <--- JS stacktrace ---> Oct 16 09:11:09 volumio volumio[1109]: FATAL ERROR: Ineffective mark-compacts near heap limit Allocation failed - JavaScript heap out of memory Oct 16 09:11:09 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:09.860+01:00 level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:44656->127.0.0.1:3000: read: connection reset by peer" Oct 16 09:11:10 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:10.042+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 16 09:11:10 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Oct 16 09:11:10 volumio systemd[1]: volumio.service: Failed with result 'signal'. Oct 16 09:11:10 volumio systemd[1]: volumio.service: Consumed 28min 2.571s CPU time. Oct 16 09:11:10 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 16 09:11:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 16 09:11:10 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 16 09:11:10 volumio systemd[1]: volumio.service: Consumed 28min 2.571s CPU time. Oct 16 09:11:10 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 16 09:11:10 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 16 09:11:11 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:11.049+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 16 09:11:12 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:12.054+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 16 09:11:13 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:13.059+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 16 09:11:13 volumio volumio-remote-updater[657]: [2026-10-16 09:11:13] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 16 09:11:13 volumio volumio-remote-updater[657]: [2026-10-16 09:11:13] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 16 09:11:13 volumio volumio-remote-updater[657]: [2026-10-16 09:11:13] [error] handle_connect error: Connection refused Oct 16 09:11:14 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:14.068+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 16 09:11:15 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:15.074+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 16 09:11:16 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:16.097+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 16 09:11:17 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:17.104+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 16 09:11:19 volumio volumio[2746]: info: ------------------------------------------- Oct 16 09:11:19 volumio volumio[2746]: info: ----- Volumio3 ---- Oct 16 09:11:19 volumio volumio[2746]: info: ------------------------------------------- Oct 16 09:11:19 volumio volumio[2746]: info: ----- System startup ---- Oct 16 09:11:19 volumio volumio[2746]: info: ------------------------------------------- Oct 16 09:11:21 volumio volumio[2746]: info: MYVOLUMIO Environment detected Oct 16 09:11:21 volumio volumio[2746]: info: Plugin folders cleanup Oct 16 09:11:21 volumio volumio[2746]: info: Scanning into folder /volumio/app/plugins/ Oct 16 09:11:21 volumio volumio[2746]: info: Scanning category audio_interface Oct 16 09:11:21 volumio volumio[2746]: info: Scanning category miscellanea Oct 16 09:11:21 volumio volumio[2746]: info: Scanning category music_service Oct 16 09:11:21 volumio volumio[2746]: info: Scanning category plugins.json Oct 16 09:11:21 volumio volumio[2746]: info: Scanning category system_controller Oct 16 09:11:21 volumio volumio[2746]: info: Scanning category user_interface Oct 16 09:11:21 volumio volumio[2746]: info: Scanning into folder /data/plugins/ Oct 16 09:11:21 volumio volumio[2746]: info: Plugin folders cleanup completed Oct 16 09:11:21 volumio volumio[2746]: info: ------------------------------------------- Oct 16 09:11:21 volumio volumio[2746]: info: ----- Core plugins startup ---- Oct 16 09:11:21 volumio volumio[2746]: info: ------------------------------------------- Oct 16 09:11:21 volumio volumio[2746]: info: Loading plugins from folder /volumio/app/plugins/ Oct 16 09:11:21 volumio volumio[2746]: info: Adding plugin upnp to MyMusic Plugins Oct 16 09:11:21 volumio volumio[2746]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 16 09:11:21 volumio volumio[2746]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 16 09:11:21 volumio volumio[2746]: info: Loading plugins from folder /data/plugins/ Oct 16 09:11:21 volumio volumio[2746]: info: Loading plugin "system"... Oct 16 09:11:22 volumio volumio[2746]: info: Loading plugin "appearance"... Oct 16 09:11:23 volumio volumio-remote-updater[657]: [2026-10-16 09:11:23] [connect] Successful connection Oct 16 09:11:25 volumio volumio[2746]: info: Loading plugin "network"... Oct 16 09:11:25 volumio volumio[2746]: info: Refreshing Cached IP Addresses Oct 16 09:11:25 volumio volumio[2746]: info: Loading plugin "services"... Oct 16 09:11:25 volumio volumio[2746]: info: Loading plugin "volumio5onboarding"... Oct 16 09:11:26 volumio volumio[2746]: info: Loading plugin "alsa_controller"... Oct 16 09:11:26 volumio sudo[2781]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 09:11:26 volumio sudo[2788]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 16 09:11:26 volumio sudo[2779]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 09:11:26 volumio sudo[2781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:26 volumio sudo[2779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:26 volumio sudo[2788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:26 volumio sudo[2781]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:26 volumio sudo[2779]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:26 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 09:11:26 volumio volumio[2746]: info: Loading plugin "wizard"... Oct 16 09:11:26 volumio volumio[2746]: info: Loading plugin "networkfs"... Oct 16 09:11:26 volumio volumio[2746]: info: Starting Udev Watcher for removable devices Oct 16 09:11:26 volumio volumio[2746]: info: Ignoring mount for partition: boot Oct 16 09:11:26 volumio volumio[2746]: info: Ignoring mount for partition: volumio Oct 16 09:11:26 volumio volumio[2746]: info: Ignoring mount for partition: volumio_data Oct 16 09:11:26 volumio volumio[2746]: info: Mounting Device RIVERDECK Oct 16 09:11:26 volumio sudo[2818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/RIVERDECK -o noatime Oct 16 09:11:26 volumio sudo[2818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:27 volumio sudo[2818]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:27 volumio volumio[2746]: mount: /media/RIVERDECK: /dev/sda1 already mounted on /media/RIVERDECK. Oct 16 09:11:27 volumio volumio[2746]: dmesg(1) may have more information after failed mount system call. Oct 16 09:11:27 volumio volumio[2746]: error: Failed to mount RIVERDECK: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/RIVERDECK" -o noatime Oct 16 09:11:27 volumio volumio[2746]: mount: /media/RIVERDECK: /dev/sda1 already mounted on /media/RIVERDECK. Oct 16 09:11:27 volumio volumio[2746]: dmesg(1) may have more information after failed mount system call. Oct 16 09:11:27 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 09:11:27 volumio volumio[2746]: info: Loading plugin "volumio_command_line_client"... Oct 16 09:11:27 volumio volumio[2746]: info: Loading plugin "upnp"... Oct 16 09:11:27 volumio volumio[2746]: info: [1792138287526] Starting Upmpd Daemon Oct 16 09:11:27 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 09:11:27 volumio volumio[2746]: info: Loading plugin "my_music"... Oct 16 09:11:27 volumio volumio[2746]: info: Loading plugin "mpd"... Oct 16 09:11:28 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:28.117+01:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:34634->127.0.0.1:3000: i/o timeout" Oct 16 09:11:28 volumio volumio[2746]: info: Loading plugin "upnp_browser"... Oct 16 09:11:31 volumio sudo[2788]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:32 volumio volumio[2746]: info: Starting UPNP Browser Oct 16 09:11:32 volumio volumio[2746]: info: Loading plugin "alarm-clock"... Oct 16 09:11:32 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:32.276+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.247:51762 @ 0x2586210" available=true connected=true macAddress=00:26:f3:3a:11:37 ip4Address=192.168.1.91/24 ip6Address= ssid=Vodafone-BDB560_EXT Oct 16 09:11:32 volumio volumio[2746]: info: Loading plugin "airplay_emulation"... Oct 16 09:11:32 volumio volumio[2746]: info: Starting Shairport Sync Oct 16 09:11:32 volumio volumio[2746]: info: Loading plugin "last_100"... Oct 16 09:11:32 volumio volumio[2746]: info: Loading plugin "webradio"... Oct 16 09:11:32 volumio volumio[2746]: info: Loading plugin "i2s_dacs"... Oct 16 09:11:32 volumio volumio[2746]: info: I2S DAC not set, start Auto-detection Oct 16 09:11:32 volumio volumio[2746]: info: Loading plugin "volumiodiscovery"... Oct 16 09:11:33 volumio volumio[2746]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 09:11:33 volumio volumio[2746]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 09:11:33 volumio volumio[2746]: *** WARNING *** For more information see Oct 16 09:11:33 volumio volumio[2746]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 09:11:33 volumio volumio[2746]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 09:11:33 volumio volumio[2746]: *** WARNING *** For more information see Oct 16 09:11:33 volumio node[2746]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 09:11:33 volumio node[2746]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 09:11:33 volumio node[2746]: *** WARNING *** For more information see Oct 16 09:11:33 volumio node[2746]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 09:11:33 volumio node[2746]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 09:11:33 volumio node[2746]: *** WARNING *** For more information see Oct 16 09:11:33 volumio volumio[2746]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 16 09:11:33 volumio volumio[2746]: info: Discovery: Started advertising with name: Volumio Oct 16 09:11:33 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 09:11:33 volumio volumio[2746]: info: Loading plugin "outputs"... Oct 16 09:11:33 volumio volumio[2746]: info: Loading plugin "albumart"... Oct 16 09:11:33 volumio volumio[2746]: info: Plugin example_plugin is not enabled Oct 16 09:11:33 volumio volumio[2746]: info: Loading plugin "inputs"... Oct 16 09:11:33 volumio volumio[2746]: info: Loading plugin "updater_comm"... Oct 16 09:11:33 volumio volumio[2746]: info: Plugin mpdemulation is not enabled Oct 16 09:11:33 volumio volumio[2746]: info: Loading plugin "rest_api"... Oct 16 09:11:33 volumio volumio[2746]: info: Loading plugin "websocket"... Oct 16 09:11:33 volumio volumio[2746]: info: Starting Socket.io Server version 1.7.4 Oct 16 09:11:33 volumio volumio[2746]: info: Loading i18n strings for locale pt Oct 16 09:11:33 volumio volumio[2746]: Updating browse sources language Oct 16 09:11:33 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::initPlayerControls Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: Express server listening on port 3000 Oct 16 09:11:34 volumio volumio[2746]: [Metrics] WebUI: 17s 125.28ms Oct 16 09:11:34 volumio volumio[2746]: info: CoreStateMachine::resetVolumioState Oct 16 09:11:34 volumio volumio[2746]: info: CoreStateMachine::getcurrentVolume Oct 16 09:11:34 volumio volumio[2823]: Forking 3 albumart workers Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 09:11:34 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 09:11:34 volumio volumio[2746]: info: Volumio Network Manager: Network status updated: 2 Oct 16 09:11:35 volumio volumio[2746]: verbose: New Socket.io Connection to 192.168.1.91 from 192.168.1.247 UA: Mozilla/5.0 (Linux; Android 17; SM-S948B Build/CP2A.260605.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 1 Oct 16 09:11:35 volumio volumio[2746]: info: VolumeController:: Volume=86 Mute =false Oct 16 09:11:35 volumio volumio[2746]: info: CoreStateMachine::pushState Oct 16 09:11:35 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:35 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 09:11:35 volumio volumio[2746]: info: CoreCommandRouter::volumioPushState Oct 16 09:11:35 volumio volumio[2746]: info: CoreStateMachine::updateTrackBlock Oct 16 09:11:35 volumio volumio[2746]: info: CorePlayQueue::getTrackBlock Oct 16 09:11:35 volumio volumio[2746]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 09:11:35 volumio volumio[2746]: info: Reloading queue from file Oct 16 09:11:35 volumio volumio[2746]: verbose: New Socket.io Connection to 192.168.1.91 from 192.168.1.247 UA: Mozilla/5.0 (Linux; Android 17; SM-S948B Build/CP2A.260605.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 2 Oct 16 09:11:35 volumio volumio[2746]: info: CoreStateMachine::setRepeat null single undefined Oct 16 09:11:35 volumio volumio[2746]: info: CoreStateMachine::pushState Oct 16 09:11:35 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:35 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 09:11:35 volumio volumio[2746]: info: CoreCommandRouter::volumioPushState Oct 16 09:11:35 volumio volumio[2746]: info: CoreStateMachine::setRandom null Oct 16 09:11:35 volumio volumio[2746]: info: CoreStateMachine::pushState Oct 16 09:11:35 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:35 volumio volumio[2746]: info: CoreCommandRouter::volumioPushState Oct 16 09:11:35 volumio volumio[2746]: info: Setting Device type: Raspberry PI Oct 16 09:11:36 volumio volumio[2746]: info: VolumeController:: Volume=86 Mute =false Oct 16 09:11:36 volumio volumio[2746]: info: CoreStateMachine::pushState Oct 16 09:11:36 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:36 volumio volumio[2746]: info: CoreCommandRouter::volumioPushState Oct 16 09:11:36 volumio volumio[2746]: verbose: New Socket.io Connection to 192.168.1.91 from 192.168.1.247 UA: Mozilla/5.0 (Linux; Android 17; SM-S948B Build/CP2A.260605.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 3 Oct 16 09:11:36 volumio volumio[2746]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Oct 16 09:11:36 volumio volumio[2746]: info: Discovery: adding 8f5c4b87-4438-43dd-b155-1dbac8b0456a Oct 16 09:11:36 volumio volumio[2746]: info: Discovery: Found device Volumio Oct 16 09:11:36 volumio volumio[2746]: info: CoreCommandRouter::volumioGetState Oct 16 09:11:36 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:36 volumio volumio[2746]: verbose: New Socket.io Connection to 192.168.1.91 from 192.168.1.247 UA: Mozilla/5.0 (Linux; Android 17; SM-S948B Build/CP2A.260605.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 4 Oct 16 09:11:36 volumio volumio[2746]: info: Completed loading Core Plugins Oct 16 09:11:36 volumio volumio[2746]: info: Preparing to generate the ALSA configuration file Oct 16 09:11:36 volumio volumio[2746]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 16 09:11:36 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 09:11:36 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 09:11:36 volumio volumio[2746]: info: Discovery: Getting this device information Oct 16 09:11:36 volumio volumio[2746]: info: CoreCommandRouter::volumioGetState Oct 16 09:11:36 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:36 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 09:11:36 volumio volumio[2746]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 16 09:11:37 volumio volumio[2746]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 16 09:11:37 volumio volumio[2746]: info: Received Get System Info Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 09:11:37 volumio volumio[2746]: info: Discovery: Getting this device information Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::volumioGetState Oct 16 09:11:37 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 09:11:37 volumio volumio[2746]: info: Asound.conf file unchanged, so no further update is needed Oct 16 09:11:37 volumio volumio[2746]: info: Output device has changed, restarting MPD Oct 16 09:11:37 volumio volumio[2746]: info: Output device has changed, restarting Shairport Sync Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 09:11:37 volumio sudo[2898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 09:11:37 volumio sudo[2898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:37 volumio sudo[2898]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:37 volumio volumio[2746]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 09:11:37 volumio volumio[2746]: info: ___________ START PLUGINS ___________ Oct 16 09:11:37 volumio sudo[2899]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 09:11:37 volumio sudo[2899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:37 volumio volumio[2746]: info: ControllerMpd::onStart: Initializing MPD Oct 16 09:11:37 volumio volumio[2746]: info: Creating MPD Configuration file Oct 16 09:11:37 volumio sudo[2907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 16 09:11:37 volumio sudo[2907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 09:11:37 volumio volumio[2746]: info: [1792138297542] CoreMusicLibrary::Adding element Servidores multimédia Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 09:11:37 volumio sudo[2909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 09:11:37 volumio volumio[2746]: info: UPNP Browser: Client initialized successfully Oct 16 09:11:37 volumio sudo[2911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 09:11:37 volumio sudo[2911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:37 volumio sudo[2909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 09:11:37 volumio sudo[2909]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:37 volumio volumio[2746]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 09:11:37 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 16 09:11:37 volumio volumio[2746]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 09:11:37 volumio volumio[2746]: info: [1792138297897] CoreMusicLibrary::Adding element Last_100 Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 09:11:37 volumio sudo[2907]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 09:11:37 volumio volumio[2746]: info: [1792138297932] CoreMusicLibrary::Adding element Webradio Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 09:11:37 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 09:11:37 volumio volumio[2746]: info: Initializing BBC Radios Oct 16 09:11:38 volumio volumio-remote-updater[657]: [2026-10-16 09:11:38] [connect] Successful connection Oct 16 09:11:38 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 09:11:38 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 09:11:38 volumio volumio[2746]: info: Volumio Calling Home Oct 16 09:11:39 volumio volumio[2837]: Starting albumart workers Oct 16 09:11:39 volumio volumio[2838]: Starting albumart workers Oct 16 09:11:39 volumio volumio[2839]: Starting albumart workers Oct 16 09:11:39 volumio volumio[2746]: verbose: New Socket.io Connection to 192.168.1.91:3000 from 192.168.1.247 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 09:11:40 volumio sudo[2940]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 09:11:40 volumio volumio[2746]: info: MPD Permissions set Oct 16 09:11:40 volumio sudo[2940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:40 volumio volumio[2746]: info: MPD Permissions set Oct 16 09:11:40 volumio sudo[2940]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:40 volumio sudo[2942]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 09:11:40 volumio sudo[2942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:40 volumio volumio-remote-updater[657]: [2026-10-16 09:11:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792138298 101 Oct 16 09:11:40 volumio sudo[2942]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:40 volumio volumio[2746]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 Oct 16 09:11:40 volumio volumio[2746]: info: Volumio called home Oct 16 09:11:40 volumio volumio[2746]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 09:11:40 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 09:11:41 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:41.091+01:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 16 09:11:41 volumio volumio[2746]: info: Starting Shairport Sync Oct 16 09:11:41 volumio volumio[2746]: info: Starting Shairport Sync Oct 16 09:11:41 volumio volumio[2746]: info: Starting Shairport Sync Oct 16 09:11:41 volumio sudo[2959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 16 09:11:41 volumio sudo[2961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 09:11:41 volumio sudo[2961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:41 volumio sudo[2959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:41 volumio sudo[2963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 09:11:41 volumio sudo[2963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:41 volumio volumio[2746]: info: Discovery: this is already registered, 8f5c4b87-4438-43dd-b155-1dbac8b0456a Oct 16 09:11:41 volumio volumio[2746]: info: Discovery: Found device Volumio Oct 16 09:11:41 volumio volumio[2746]: info: CoreCommandRouter::volumioGetState Oct 16 09:11:41 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:41 volumio sudo[2965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 09:11:41 volumio sudo[2965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 16 09:11:41 volumio sudo[2959]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 16 09:11:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 09:11:41 volumio systemd[1]: shairport-sync.service: Consumed 6min 13.836s CPU time. Oct 16 09:11:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 16 09:11:41 volumio sudo[2963]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:42 volumio sudo[2961]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:42 volumio sudo[2965]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:42 volumio volumio[2746]: verbose: New Socket.io Connection to 192.168.1.91 from 192.168.1.247 UA: Mozilla/5.0 (Linux; Android 17; SM-S948B Build/CP2A.260605.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 16 09:11:42 volumio volumio[2746]: info: Shairport-Sync Started Oct 16 09:11:42 volumio volumio[2746]: Error adding Membership: Error: addMembership EINVAL Oct 16 09:11:42 volumio volumio[2746]: info: Shairport-Sync Started Oct 16 09:11:42 volumio volumio[2746]: info: Shairport-Sync Started Oct 16 09:11:42 volumio volumio[2746]: info: Upmpdcli Daemon Started Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::volumioGetState Oct 16 09:11:42 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::volumioGetState Oct 16 09:11:42 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::volumioGetQueue Oct 16 09:11:42 volumio volumio[2746]: info: CoreStateMachine::getQueue Oct 16 09:11:42 volumio volumio[2746]: info: CorePlayQueue::getQueue Oct 16 09:11:42 volumio volumio[2746]: info: Listing playlists Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 09:11:42 volumio volumio[2746]: info: Received Get System Info Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 09:11:42 volumio volumio[2746]: info: Discovery: Getting this device information Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::volumioGetState Oct 16 09:11:42 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 09:11:42 volumio volumio[2746]: info: CoreCommandRouter::volumioGetState Oct 16 09:11:42 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:46 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 16 09:11:46 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 16 09:11:46 volumio systemd[1]: mpd.service: Consumed 2h 19min 30.763s CPU time. Oct 16 09:11:46 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 16 09:11:46 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 16 09:11:46 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 16 09:11:46 volumio volumio[2746]: error: Upnp client error: Error: read ECONNRESET Oct 16 09:11:46 volumio volumio[2746]: error: Upnp client error: Error: read ECONNRESET Oct 16 09:11:46 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 16 09:11:46 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.009+01:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.066+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=56.689175ms Oct 16 09:11:47 volumio sudo[3022]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 16 09:11:47 volumio sudo[3022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.129+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=http://pushupdates.volumio.org duration=117.582618ms Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.131+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=114.356734ms Oct 16 09:11:47 volumio sudo[3022]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.143+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=133.414636ms Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.144+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=123.746362ms Oct 16 09:11:47 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 09:11:47 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 09:11:47 volumio volumio[2746]: info: Discovery: Getting this device information Oct 16 09:11:47 volumio volumio[2746]: info: CoreCommandRouter::volumioGetState Oct 16 09:11:47 volumio volumio[2746]: info: CorePlayQueue::getTrack 0 Oct 16 09:11:47 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.213+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=https://functions.volumio.cloud duration=201.665989ms Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.215+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=https://www.googleapis.com duration=192.635736ms Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.217+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=https://database.volumio.cloud duration=196.477243ms Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.220+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=https://functions.volumio.cloud duration=205.430362ms Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.223+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=http://plugins.volumio.org duration=204.603591ms Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.229+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=https://securetoken.googleapis.com duration=204.665518ms Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.253+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=https://google.com duration=241.279917ms Oct 16 09:11:47 volumio volumio5-onboarding[1555]: time=2026-10-16T09:11:47.269+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.247:51762 @ 0x2586210" latency=64.613835ms timeout=10s endpoint=http://cddb.volumio.org duration=258.187821ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 09:11:47 volumio volumio[2746]: verbose: New Socket.io Connection to 192.168.1.91:3000 from 192.168.1.247 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 16 09:11:47 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 09:11:47 volumio volumio[2746]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 09:11:52 volumio volumio[2746]: info: Preload queue cleared Oct 16 09:11:52 volumio volumio[2746]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 09:11:52 volumio volumio[2746]: info: CoreStateMachine::ClearQueue Oct 16 09:11:52 volumio volumio[2746]: info: CoreStateMachine::stop Oct 16 09:11:52 volumio volumio[2746]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 09:11:52 volumio volumio[2746]: info: CorePlayQueue::clearPlayQueue Oct 16 09:11:52 volumio volumio[2746]: info: CorePlayQueue::saveQueue Oct 16 09:11:52 volumio volumio[2746]: info: CoreCommandRouter::volumioPushQueue Oct 16 09:11:52 volumio volumio[2746]: info: CoreStateMachine::addQueueItems Oct 16 09:11:52 volumio volumio[2746]: info: CorePlayQueue::addQueueItems Oct 16 09:11:52 volumio volumio[2746]: info: Preload queue cleared Oct 16 09:11:52 volumio volumio[2746]: info: Adding Item to queue: music-library/USB/RIVERDECK/2010-10-09_4h13m24.wav Oct 16 09:11:52 volumio volumio[2746]: info: Exploding uri music-library/USB/RIVERDECK/2010-10-09_4h13m24.wav in service mpd Oct 16 09:11:53 volumio volumio[2746]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 09:11:53 volumio volumio[2746]: TypeError: Cannot read properties of undefined (reading 'then') Oct 16 09:11:53 volumio volumio[2746]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2541:19) Oct 16 09:11:53 volumio volumio[2746]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2339:21) Oct 16 09:11:53 volumio volumio[2746]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Oct 16 09:11:53 volumio volumio[2746]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Oct 16 09:11:53 volumio volumio[2746]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Oct 16 09:11:53 volumio volumio[2746]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Oct 16 09:11:53 volumio volumio[2746]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Oct 16 09:11:53 volumio volumio[2746]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Oct 16 09:11:53 volumio volumio[2746]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Oct 16 09:11:53 volumio volumio[2746]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Oct 16 09:11:53 volumio volumio[2746]: at Socket.emit (node:events:514:28) Oct 16 09:11:53 volumio volumio[2746]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 16 09:11:53 volumio volumio[2746]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 16 09:11:53 volumio volumio[2746]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 09:11:54 volumio mpd[3024]: 2026-10-16T09:11:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 09:11:54 volumio sudo[3046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 09:10' Oct 16 09:11:54 volumio sudo[3046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 16 09:11:54 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 16 09:11:54 volumio sudo[2899]: pam_unix(sudo:session): session closed for user root Oct 16 09:11:54 volumio sudo[2911]: pam_unix(sudo:session): session closed for user root 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"