Apr 12 00:32:04 leczyca volumio[15996]: info: ------------------------------------------- Apr 12 00:32:04 leczyca volumio[15996]: info: ----- Volumio3 ---- Apr 12 00:32:04 leczyca volumio[15996]: info: ------------------------------------------- Apr 12 00:32:04 leczyca volumio[15996]: info: ----- System startup ---- Apr 12 00:32:04 leczyca volumio[15996]: info: ------------------------------------------- Apr 12 00:32:11 leczyca volumio[15996]: info: MYVOLUMIO Environment detected Apr 12 00:32:12 leczyca volumio[15996]: info: Plugin folders cleanup Apr 12 00:32:12 leczyca volumio[15996]: info: Scanning into folder /volumio/app/plugins/ Apr 12 00:32:12 leczyca volumio[15996]: info: Scanning category audio_interface Apr 12 00:32:12 leczyca volumio[15996]: info: Scanning category miscellanea Apr 12 00:32:12 leczyca volumio[15996]: info: Scanning category music_service Apr 12 00:32:12 leczyca volumio[15996]: info: Scanning category plugins.json Apr 12 00:32:12 leczyca volumio[15996]: info: Scanning category system_controller Apr 12 00:32:12 leczyca volumio[15996]: info: Scanning category user_interface Apr 12 00:32:12 leczyca volumio[15996]: info: Scanning into folder /data/plugins/ Apr 12 00:32:12 leczyca volumio[15996]: info: Plugin folders cleanup completed Apr 12 00:32:12 leczyca volumio[15996]: info: ------------------------------------------- Apr 12 00:32:12 leczyca volumio[15996]: info: ----- Core plugins startup ---- Apr 12 00:32:12 leczyca volumio[15996]: info: ------------------------------------------- Apr 12 00:32:12 leczyca volumio[15996]: info: Loading plugins from folder /volumio/app/plugins/ Apr 12 00:32:12 leczyca volumio[15996]: info: Adding plugin upnp to MyMusic Plugins Apr 12 00:32:12 leczyca volumio[15996]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 12 00:32:12 leczyca volumio[15996]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 12 00:32:12 leczyca volumio[15996]: info: Loading plugins from folder /data/plugins/ Apr 12 00:32:12 leczyca volumio[15996]: info: Loading plugin "system"... Apr 12 00:32:13 leczyca systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 417. Apr 12 00:32:13 leczyca systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 00:32:13 leczyca systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 00:32:13 leczyca volumio[15996]: info: No id defined. Creating one Apr 12 00:32:14 leczyca volumio[15996]: info: Loading plugin "appearance"... Apr 12 00:32:14 leczyca upmpdcli[16028]: Could not open config: /tmp/upmpdcli.conf Apr 12 00:32:14 leczyca systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 12 00:32:14 leczyca systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 12 00:32:22 leczyca volumio[15996]: info: Loading plugin "network"... Apr 12 00:32:22 leczyca volumio[15996]: info: Refreshing Cached IP Addresses Apr 12 00:32:23 leczyca sudo[16033]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 00:32:23 leczyca sudo[16033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 00:32:23 leczyca sudo[16033]: pam_unix(sudo:session): session closed for user root Apr 12 00:32:23 leczyca sudo[16035]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 00:32:24 leczyca volumio[15996]: info: Loading plugin "services"... Apr 12 00:32:24 leczyca sudo[16035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 00:32:24 leczyca volumio[15996]: info: Loading plugin "alsa_controller"... Apr 12 00:32:24 leczyca sudo[16035]: pam_unix(sudo:session): session closed for user root Apr 12 00:32:24 leczyca tailscaled[488]: health(warnable=no-derp-connection): ok Apr 12 00:32:24 leczyca sudo[16043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 12 00:32:24 leczyca sudo[16043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 00:32:25 leczyca sudo[16043]: pam_unix(sudo:session): session closed for user root Apr 12 00:32:25 leczyca volumio[15996]: warn: The ALSA output card is not set, defaulting to card [object Object] Apr 12 00:32:26 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 00:32:26 leczyca volumio[15996]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Apr 12 00:32:26 leczyca volumio[15996]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 00:32:26 leczyca volumio[15996]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 00:32:27 leczyca volumio[15996]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 00:32:27 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 00:32:27 leczyca volumio[15996]: info: Plugin wizard is not enabled Apr 12 00:32:27 leczyca volumio[15996]: info: Plugin networkfs is not enabled Apr 12 00:32:27 leczyca volumio[15996]: info: Loading plugin "volumio_command_line_client"... Apr 12 00:32:27 leczyca volumio[15996]: info: Plugin upnp is not enabled Apr 12 00:32:27 leczyca volumio[15996]: info: Loading plugin "my_music"... Apr 12 00:32:27 leczyca volumio[15996]: info: Plugin mpd is not enabled Apr 12 00:32:27 leczyca volumio[15996]: info: Plugin upnp_browser is not enabled Apr 12 00:32:27 leczyca volumio[15996]: info: Plugin alarm-clock is not enabled Apr 12 00:32:27 leczyca volumio[15996]: info: Plugin airplay_emulation is not enabled Apr 12 00:32:27 leczyca volumio[15996]: info: Plugin last_100 is not enabled Apr 12 00:32:27 leczyca volumio[15996]: info: Plugin webradio is not enabled Apr 12 00:32:27 leczyca volumio[15996]: info: Loading plugin "i2s_dacs"... Apr 12 00:32:27 leczyca volumio[15996]: info: I2S DAC not set, start Auto-detection Apr 12 00:32:27 leczyca volumio[15996]: info: Plugin volumiodiscovery is not enabled Apr 12 00:32:27 leczyca volumio[15996]: info: Loading plugin "outputs"... Apr 12 00:32:28 leczyca volumio[15996]: info: Plugin albumart is not enabled Apr 12 00:32:28 leczyca volumio[15996]: info: Plugin example_plugin is not enabled Apr 12 00:32:28 leczyca volumio[15996]: info: Loading plugin "inputs"... Apr 12 00:32:28 leczyca volumio[15996]: info: Plugin updater_comm is not enabled Apr 12 00:32:28 leczyca volumio[15996]: info: Plugin mpdemulation is not enabled Apr 12 00:32:28 leczyca volumio[15996]: info: Loading plugin "rest_api"... Apr 12 00:32:29 leczyca volumio[15996]: info: Loading plugin "websocket"... Apr 12 00:32:30 leczyca systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 418. Apr 12 00:32:30 leczyca systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 00:32:30 leczyca systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 00:32:31 leczyca upmpdcli[16083]: Could not open config: /tmp/upmpdcli.conf Apr 12 00:32:31 leczyca volumio[15996]: info: Starting Socket.io Server version 1.7.4 Apr 12 00:32:31 leczyca systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 12 00:32:31 leczyca systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 12 00:32:31 leczyca volumio[15996]: info: Loading i18n strings for locale en Apr 12 00:32:31 leczyca volumio[15996]: Updating browse sources language Apr 12 00:32:31 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::initPlayerControls Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 00:32:33 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 00:32:34 leczyca volumio[15996]: Express server listening on port 3000 Apr 12 00:32:34 leczyca volumio[15996]: [Metrics] WebUI: 33s 258.89ms Apr 12 00:32:34 leczyca volumio[15996]: info: CoreStateMachine::resetVolumioState Apr 12 00:32:34 leczyca volumio[15996]: info: CoreStateMachine::getcurrentVolume Apr 12 00:32:34 leczyca volumio[15996]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 00:32:34 leczyca tailscaled[488]: health(warnable=no-derp-connection): error: Tailscale could not connect to the 'Chicago' relay server. Your Internet connection might be down, or the server might be temporarily unavailable. Apr 12 00:32:35 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 00:32:36 leczyca volumio[15996]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 12 00:32:36 leczyca volumio[15996]: info: CoreStateMachine::getcurrentVolume Apr 12 00:32:36 leczyca volumio[15996]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 00:32:36 leczyca volumio[15996]: info: Cannot read play queue from file Apr 12 00:32:37 leczyca volumio[15996]: info: Volumio Network Manager: Network status updated: 1 Apr 12 00:32:37 leczyca volumio[15996]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 12 00:32:37 leczyca volumio[15996]: wlan0 Interface doesn't support scanning. Apr 12 00:32:37 leczyca volumio[15996]: info: Cannot use regular scanning, forcing with ap-force Apr 12 00:32:38 leczyca sudo[16106]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 12 00:32:38 leczyca sudo[16106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 00:32:38 leczyca sudo[16106]: pam_unix(sudo:session): session closed for user root Apr 12 00:32:38 leczyca volumio[15996]: command failed: No such device (-19) Apr 12 00:32:38 leczyca volumio[15996]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 12 00:32:38 leczyca volumio[15996]: command failed: No such device (-19) Apr 12 00:32:39 leczyca volumio[15996]: info: VolumeController:: Volume=86 Mute =false Apr 12 00:32:39 leczyca volumio[15996]: info: CoreStateMachine::pushState Apr 12 00:32:39 leczyca volumio[15996]: info: CorePlayQueue::getTrack 0 Apr 12 00:32:39 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 00:32:39 leczyca volumio[15996]: info: CoreCommandRouter::volumioPushState Apr 12 00:32:39 leczyca volumio[15996]: info: CoreStateMachine::updateTrackBlock Apr 12 00:32:39 leczyca volumio[15996]: info: CorePlayQueue::getTrackBlock Apr 12 00:32:39 leczyca volumio[15996]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 00:32:40 leczyca volumio[15996]: info: Setting Device type: Raspberry PI Apr 12 00:32:40 leczyca volumio[15996]: info: VolumeController:: Volume=86 Mute =false Apr 12 00:32:40 leczyca volumio[15996]: info: CoreStateMachine::pushState Apr 12 00:32:40 leczyca volumio[15996]: info: CorePlayQueue::getTrack 0 Apr 12 00:32:40 leczyca volumio[15996]: info: CoreCommandRouter::volumioPushState Apr 12 00:32:40 leczyca volumio[15996]: info: CoreStateMachine::updateTrackBlock Apr 12 00:32:40 leczyca volumio[15996]: info: CorePlayQueue::getTrackBlock Apr 12 00:32:40 leczyca volumio[15996]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 00:32:40 leczyca volumio[15996]: info: VolumeController:: Volume=86 Mute =false Apr 12 00:32:40 leczyca volumio[15996]: info: CoreStateMachine::pushState Apr 12 00:32:40 leczyca volumio[15996]: info: CorePlayQueue::getTrack 0 Apr 12 00:32:40 leczyca volumio[15996]: info: CoreCommandRouter::volumioPushState Apr 12 00:32:42 leczyca volumio[15996]: info: VolumeController:: Volume=86 Mute =false Apr 12 00:32:42 leczyca volumio[15996]: info: CoreStateMachine::pushState Apr 12 00:32:42 leczyca volumio[15996]: info: CorePlayQueue::getTrack 0 Apr 12 00:32:42 leczyca volumio[15996]: info: CoreCommandRouter::volumioPushState Apr 12 00:32:42 leczyca volumio[15996]: info: CorePlayQueue::getTrack 0 Apr 12 00:32:43 leczyca volumio[15996]: info: Completed loading Core Plugins Apr 12 00:32:43 leczyca volumio[15996]: info: Preparing to generate the ALSA configuration file Apr 12 00:32:44 leczyca volumio[15996]: info: Asound.conf file written Apr 12 00:32:44 leczyca sudo[16126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 12 00:32:44 leczyca sudo[16126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 00:32:44 leczyca sudo[16126]: pam_unix(sudo:session): session closed for user root Apr 12 00:32:45 leczyca volumio[15996]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 12 00:32:45 leczyca volumio[15996]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 12 00:32:45 leczyca volumio[15996]: info: ___________ START PLUGINS ___________ Apr 12 00:32:45 leczyca volumio[15996]: info: Setting Hotspot Unique name on first start: Volumio-698FA Apr 12 00:32:45 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Apr 12 00:32:45 leczyca volumio[15996]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Apr 12 00:32:45 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Apr 12 00:32:45 leczyca volumio[15996]: info: CoreCommandRouter::Reload Ui Apr 12 00:32:45 leczyca volumio[15996]: info: Volumio Calling Home Apr 12 00:32:45 leczyca volumio[15996]: info: Completed starting Core Plugins Apr 12 00:32:45 leczyca volumio[15996]: info: ------------------------------------------- Apr 12 00:32:45 leczyca volumio[15996]: info: ----- MyVolumio plugins startup ---- Apr 12 00:32:45 leczyca volumio[15996]: info: ------------------------------------------- Apr 12 00:32:45 leczyca volumio[15996]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 12 00:32:46 leczyca systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 419. Apr 12 00:32:46 leczyca systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 00:32:46 leczyca systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 12 00:32:48 leczyca volumio[15996]: info: Volumio called home Apr 12 00:32:48 leczyca sudo[16144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 12 00:32:48 leczyca sudo[16144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 00:32:48 leczyca sudo[16144]: pam_unix(sudo:session): session closed for user root Apr 12 00:32:49 leczyca volumio[15996]: info: Permissions for /etc/hostapd/hostapd.conf Apr 12 00:32:49 leczyca volumio[15996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 12 00:32:49 leczyca upmpdcli[16137]: Could not open config: /tmp/upmpdcli.conf Apr 12 00:32:49 leczyca systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 12 00:32:49 leczyca systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 12 00:32:50 leczyca sudo[16154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 12 00:32:50 leczyca sudo[16154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 00:32:50 leczyca volumio[15996]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 00:32:50 leczyca volumio[15996]: TypeError: f.split(...)[0].capitalize is not a function Apr 12 00:32:50 leczyca volumio[15996]: at /volumio/app/plugins/miscellanea/appearance/index.js:191:56 Apr 12 00:32:50 leczyca volumio[15996]: at Array.forEach () Apr 12 00:32:50 leczyca volumio[15996]: at /volumio/app/plugins/miscellanea/appearance/index.js:189:11 Apr 12 00:32:50 leczyca volumio[15996]: at /volumio/node_modules/graceful-fs/graceful-fs.js:228:16 Apr 12 00:32:50 leczyca volumio[15996]: at FSReqCallback.oncomplete (node:fs:190:23) Apr 12 00:32:50 leczyca volumio[15996]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 00:32:51 leczyca sudo[16013]: pam_unix(sudo:session): session closed for user root Apr 12 00:32:51 leczyca systemd[1]: Starting wireless.service - Wireless Services... Apr 12 00:33:03 leczyca wireless.js[16159]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Apr 12 00:33:03 leczyca wireless.js[16159]: WIRELESS.JS - INFO: === SNM TRANSITION === Apr 12 00:33:03 leczyca wireless.js[16159]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Apr 12 00:33:03 leczyca wireless.js[16159]: WIRELESS.JS - INFO: New ethernet state: connected Apr 12 00:33:03 leczyca wireless.js[16159]: WIRELESS.JS - INFO: Single Network Mode: enabled Apr 12 00:33:03 leczyca wireless.js[16159]: WIRELESS.JS - INFO: First start: yes Apr 12 00:33:03 leczyca wireless.js[16159]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Apr 12 00:33:03 leczyca wireless.js[16159]: WIRELESS.JS - INFO: === END TRANSITION === Apr 12 00:33:03 leczyca wireless.js[16159]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Apr 12 00:33:03 leczyca wireless.js[16159]: WIRELESS.JS - INFO: ERROR: wlan0 does not exist, exiting... Apr 12 00:33:03 leczyca systemd[1]: wireless.service: Main process exited, code=exited, status=1/FAILURE Apr 12 00:33:03 leczyca sudo[16200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 00:32' Apr 12 00:33:03 leczyca systemd[1]: wireless.service: Killing process 16201 (sh) with signal SIGKILL. Apr 12 00:33:03 leczyca sudo[16200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 00:33:03 leczyca systemd[1]: wireless.service: Killing process 16202 (pkill) with signal SIGKILL. Apr 12 00:33:04 leczyca sudo[16154]: pam_unix(sudo:session): session closed for user root Apr 12 00:33:04 leczyca systemd[1]: wireless.service: Failed with result 'exit-code'. Apr 12 00:33:04 leczyca systemd[1]: Failed to start wireless.service - Wireless Services. Apr 12 00:33:04 leczyca systemd[1]: wireless.service: Consumed 5.707s CPU time. 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"