Oct 11 18:24:04 volumio volumio-remote-updater[755]: [2024-10-11 18:24:04] [info] asio async_connect error: system:111 (Connection refused) Oct 11 18:24:04 volumio volumio-remote-updater[755]: [2024-10-11 18:24:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 11 18:24:04 volumio volumio-remote-updater[755]: [2024-10-11 18:24:04] [error] handle_connect error: Underlying Transport Error Oct 11 18:24:14 volumio volumio-remote-updater[755]: [2024-10-11 18:24:14] [info] asio async_connect error: system:111 (Connection refused) Oct 11 18:24:14 volumio volumio-remote-updater[755]: [2024-10-11 18:24:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 11 18:24:14 volumio volumio-remote-updater[755]: [2024-10-11 18:24:14] [error] handle_connect error: Underlying Transport Error Oct 11 18:24:24 volumio volumio-remote-updater[755]: [2024-10-11 18:24:24] [info] asio async_connect error: system:111 (Connection refused) Oct 11 18:24:24 volumio volumio-remote-updater[755]: [2024-10-11 18:24:24] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 11 18:24:24 volumio volumio-remote-updater[755]: [2024-10-11 18:24:24] [error] handle_connect error: Underlying Transport Error Oct 11 18:24:31 volumio sudo[2812]: pam_unix(sudo:session): session closed for user root Oct 11 18:24:34 volumio volumio-remote-updater[755]: [2024-10-11 18:24:34] [info] asio async_connect error: system:111 (Connection refused) Oct 11 18:24:34 volumio volumio-remote-updater[755]: [2024-10-11 18:24:34] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 11 18:24:34 volumio volumio-remote-updater[755]: [2024-10-11 18:24:34] [error] handle_connect error: Underlying Transport Error Oct 11 18:24:44 volumio volumio-remote-updater[755]: [2024-10-11 18:24:44] [info] asio async_connect error: system:111 (Connection refused) Oct 11 18:24:44 volumio volumio-remote-updater[755]: [2024-10-11 18:24:44] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 11 18:24:44 volumio volumio-remote-updater[755]: [2024-10-11 18:24:44] [error] handle_connect error: Underlying Transport Error Oct 11 18:24:54 volumio volumio-remote-updater[755]: [2024-10-11 18:24:54] [info] asio async_connect error: system:111 (Connection refused) Oct 11 18:24:54 volumio volumio-remote-updater[755]: [2024-10-11 18:24:54] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 11 18:24:54 volumio volumio-remote-updater[755]: [2024-10-11 18:24:54] [error] handle_connect error: Underlying Transport Error Oct 11 18:25:03 volumio sudo[2867]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl --system --full --legend=no --no-pager --plain list-unit-files --state enabled,enabled-runtime,linked,linked-runtime,static,indirect,disabled,generated,transient vol* Oct 11 18:25:03 volumio sudo[2867]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Oct 11 18:25:03 volumio sudo[2867]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:03 volumio sudo[2872]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl --system --full --legend=no --no-pager --plain list-units vol* Oct 11 18:25:03 volumio sudo[2872]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Oct 11 18:25:03 volumio sudo[2872]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:03 volumio sudo[2876]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl --system --full --legend=no --no-pager --plain show --property Names,CanStart=yes -- volumio-remote-updater.service volumio.service volumio_cpu_tweak.service volumiologrotate.service volumiossh.service Oct 11 18:25:03 volumio sudo[2876]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Oct 11 18:25:03 volumio systemd[1]: /lib/systemd/system/volumiologrotate.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 11 18:25:03 volumio systemd[1]: /lib/systemd/system/volumiologrotate.service:8: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 11 18:25:03 volumio sudo[2876]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:04 volumio volumio-remote-updater[755]: [2024-10-11 18:25:04] [info] asio async_connect error: system:111 (Connection refused) Oct 11 18:25:04 volumio volumio-remote-updater[755]: [2024-10-11 18:25:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 11 18:25:04 volumio volumio-remote-updater[755]: [2024-10-11 18:25:04] [error] handle_connect error: Underlying Transport Error Oct 11 18:25:05 volumio sudo[2887]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl --system --full --legend=no --no-pager --plain list-unit-files --state enabled,enabled-runtime,linked,linked-runtime,static,indirect,disabled,generated,transient volumio.ser* Oct 11 18:25:05 volumio sudo[2887]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Oct 11 18:25:05 volumio sudo[2887]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:05 volumio sudo[2892]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl --system --full --legend=no --no-pager --plain list-units volumio.ser* Oct 11 18:25:05 volumio sudo[2892]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Oct 11 18:25:05 volumio sudo[2892]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:05 volumio sudo[2896]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl --system --full --legend=no --no-pager --plain show --property Names,CanStart=yes -- volumio.service Oct 11 18:25:05 volumio sudo[2896]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Oct 11 18:25:05 volumio sudo[2896]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:07 volumio sudo[2900]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl restart volumio.service Oct 11 18:25:07 volumio sudo[2900]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Oct 11 18:25:07 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 11 18:25:07 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 11 18:25:07 volumio sudo[2900]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:07 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Oct 11 18:25:09 volumio volumio[2905]: info: ------------------------------------------- Oct 11 18:25:09 volumio volumio[2905]: info: ----- Volumio3 ---- Oct 11 18:25:09 volumio volumio[2905]: info: ------------------------------------------- Oct 11 18:25:09 volumio volumio[2905]: info: ----- System startup ---- Oct 11 18:25:09 volumio volumio[2905]: info: ------------------------------------------- Oct 11 18:25:09 volumio volumio[2905]: info: MYVOLUMIO Environment detected Oct 11 18:25:09 volumio volumio[2905]: info: Plugin folders cleanup Oct 11 18:25:09 volumio volumio[2905]: info: Scanning into folder /volumio/app/plugins/ Oct 11 18:25:09 volumio volumio[2905]: info: Scanning category audio_interface Oct 11 18:25:09 volumio volumio[2905]: info: Scanning category miscellanea Oct 11 18:25:09 volumio volumio[2905]: info: Scanning category music_service Oct 11 18:25:09 volumio volumio[2905]: info: Scanning category plugins.json Oct 11 18:25:09 volumio volumio[2905]: info: Scanning category system_controller Oct 11 18:25:09 volumio volumio[2905]: info: Scanning category user_interface Oct 11 18:25:09 volumio volumio[2905]: info: Scanning into folder /data/plugins/ Oct 11 18:25:09 volumio volumio[2905]: info: Plugin folders cleanup completed Oct 11 18:25:09 volumio volumio[2905]: info: ------------------------------------------- Oct 11 18:25:09 volumio volumio[2905]: info: ----- Core plugins startup ---- Oct 11 18:25:09 volumio volumio[2905]: info: ------------------------------------------- Oct 11 18:25:09 volumio volumio[2905]: info: Loading plugins from folder /volumio/app/plugins/ Oct 11 18:25:09 volumio volumio[2905]: info: Adding plugin upnp to MyMusic Plugins Oct 11 18:25:09 volumio volumio[2905]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 11 18:25:09 volumio volumio[2905]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 11 18:25:09 volumio volumio[2905]: info: Loading plugins from folder /data/plugins/ Oct 11 18:25:09 volumio volumio[2905]: info: Loading plugin "system"... Oct 11 18:25:09 volumio volumio[2905]: info: No id defined. Creating one Oct 11 18:25:09 volumio volumio[2905]: info: Loading plugin "appearance"... Oct 11 18:25:10 volumio volumio[2905]: info: Loading plugin "network"... Oct 11 18:25:10 volumio volumio[2905]: info: Refreshing Cached IP Addresses Oct 11 18:25:10 volumio sudo[2934]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 18:25:10 volumio sudo[2934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:10 volumio sudo[2936]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 18:25:10 volumio volumio[2905]: info: Loading plugin "services"... Oct 11 18:25:10 volumio volumio[2905]: info: Loading plugin "alsa_controller"... Oct 11 18:25:10 volumio sudo[2934]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:10 volumio sudo[2936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:10 volumio sudo[2936]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:10 volumio sudo[2939]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 11 18:25:10 volumio sudo[2939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:10 volumio volumio[2905]: warn: The ALSA output card is not set, defaulting to card [object Object] Oct 11 18:25:10 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 11 18:25:10 volumio volumio[2905]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Oct 11 18:25:10 volumio volumio[2905]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 11 18:25:10 volumio volumio[2905]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 11 18:25:10 volumio volumio[2905]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 11 18:25:10 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 18:25:10 volumio volumio[2905]: info: Loading plugin "wizard"... Oct 11 18:25:10 volumio volumio[2905]: info: Loading plugin "networkfs"... Oct 11 18:25:10 volumio volumio[2905]: info: Starting Udev Watcher for removable devices Oct 11 18:25:10 volumio volumio[2905]: info: Ignoring mount for partition: boot Oct 11 18:25:10 volumio volumio[2905]: info: Ignoring mount for partition: volumio Oct 11 18:25:10 volumio volumio[2905]: info: Ignoring mount for partition: volumio_data Oct 11 18:25:10 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 18:25:10 volumio volumio[2905]: info: Loading plugin "volumio_command_line_client"... Oct 11 18:25:10 volumio volumio[2905]: info: Loading plugin "upnp"... Oct 11 18:25:10 volumio volumio[2905]: info: [1728671110859] Starting Upmpd Daemon Oct 11 18:25:10 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 18:25:10 volumio volumio[2905]: info: Loading plugin "my_music"... Oct 11 18:25:10 volumio volumio[2905]: info: Loading plugin "mpd"... Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "upnp_browser"... Oct 11 18:25:11 volumio volumio[2905]: error: !!!! WARNING !!!! Oct 11 18:25:11 volumio volumio[2905]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: Error: The module '/volumio/node_modules/libxmljs/build/Release/xmljs.node' Oct 11 18:25:11 volumio volumio[2905]: was compiled against a different Node.js version using Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 115. This version of Node.js requires Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 127. Please try re-compiling or re-installing Oct 11 18:25:11 volumio volumio[2905]: the module (for instance, using `npm rebuild` or `npm install`). Oct 11 18:25:11 volumio volumio[2905]: error: Stack trace: Error: The module '/volumio/node_modules/libxmljs/build/Release/xmljs.node' Oct 11 18:25:11 volumio volumio[2905]: was compiled against a different Node.js version using Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 115. This version of Node.js requires Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 127. Please try re-compiling or re-installing Oct 11 18:25:11 volumio volumio[2905]: the module (for instance, using `npm rebuild` or `npm install`). Oct 11 18:25:11 volumio volumio[2905]: at Module._extensions..node (node:internal/modules/cjs/loader:1717:18) Oct 11 18:25:11 volumio volumio[2905]: at Module.load (node:internal/modules/cjs/loader:1317:32) Oct 11 18:25:11 volumio volumio[2905]: at Module._load (node:internal/modules/cjs/loader:1127:12) Oct 11 18:25:11 volumio volumio[2905]: at TracingChannel.traceSync (node:diagnostics_channel:315:14) Oct 11 18:25:11 volumio volumio[2905]: at wrapModuleLoad (node:internal/modules/cjs/loader:217:24) Oct 11 18:25:11 volumio volumio[2905]: at Module.require (node:internal/modules/cjs/loader:1339:12) Oct 11 18:25:11 volumio volumio[2905]: at require (node:internal/modules/helpers:135:16) Oct 11 18:25:11 volumio volumio[2905]: at bindings (/volumio/node_modules/libxmljs/node_modules/bindings/bindings.js:84:48) Oct 11 18:25:11 volumio volumio[2905]: at Object. (/volumio/node_modules/libxmljs/dist/lib/bindings/index.js:5:16) Oct 11 18:25:11 volumio volumio[2905]: at Module._compile (node:internal/modules/cjs/loader:1546:14) Oct 11 18:25:11 volumio volumio[2905]: error: !!!! WARNING !!!! Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "alarm-clock"... Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "airplay_emulation"... Oct 11 18:25:11 volumio volumio[2905]: info: Starting Shairport Sync Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "last_100"... Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "webradio"... Oct 11 18:25:11 volumio volumio[2905]: error: !!!! WARNING !!!! Oct 11 18:25:11 volumio volumio[2905]: error: The plugin music_service/webradio failed to load, setting it to stopped. Error: Error: The module '/volumio/node_modules/libxmljs/build/Release/xmljs.node' Oct 11 18:25:11 volumio volumio[2905]: was compiled against a different Node.js version using Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 115. This version of Node.js requires Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 127. Please try re-compiling or re-installing Oct 11 18:25:11 volumio volumio[2905]: the module (for instance, using `npm rebuild` or `npm install`). Oct 11 18:25:11 volumio volumio[2905]: error: Stack trace: Error: The module '/volumio/node_modules/libxmljs/build/Release/xmljs.node' Oct 11 18:25:11 volumio volumio[2905]: was compiled against a different Node.js version using Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 115. This version of Node.js requires Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 127. Please try re-compiling or re-installing Oct 11 18:25:11 volumio volumio[2905]: the module (for instance, using `npm rebuild` or `npm install`). Oct 11 18:25:11 volumio volumio[2905]: at Module._extensions..node (node:internal/modules/cjs/loader:1717:18) Oct 11 18:25:11 volumio volumio[2905]: at Module.load (node:internal/modules/cjs/loader:1317:32) Oct 11 18:25:11 volumio volumio[2905]: at Module._load (node:internal/modules/cjs/loader:1127:12) Oct 11 18:25:11 volumio volumio[2905]: at TracingChannel.traceSync (node:diagnostics_channel:315:14) Oct 11 18:25:11 volumio volumio[2905]: at wrapModuleLoad (node:internal/modules/cjs/loader:217:24) Oct 11 18:25:11 volumio volumio[2905]: at Module.require (node:internal/modules/cjs/loader:1339:12) Oct 11 18:25:11 volumio volumio[2905]: at require (node:internal/modules/helpers:135:16) Oct 11 18:25:11 volumio volumio[2905]: at bindings (/volumio/node_modules/libxmljs/node_modules/bindings/bindings.js:84:48) Oct 11 18:25:11 volumio volumio[2905]: at Object. (/volumio/node_modules/libxmljs/dist/lib/bindings/index.js:5:16) Oct 11 18:25:11 volumio volumio[2905]: at Module._compile (node:internal/modules/cjs/loader:1546:14) Oct 11 18:25:11 volumio volumio[2905]: error: !!!! WARNING !!!! Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "i2s_dacs"... Oct 11 18:25:11 volumio volumio[2905]: info: I2S DAC not set, start Auto-detection Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "volumiodiscovery"... Oct 11 18:25:11 volumio volumio[2905]: error: !!!! WARNING !!!! Oct 11 18:25:11 volumio volumio[2905]: error: The plugin system_controller/volumiodiscovery failed to load, setting it to stopped. Error: Error: The module '/volumio/node_modules/mdns/build/Release/dns_sd_bindings.node' Oct 11 18:25:11 volumio volumio[2905]: was compiled against a different Node.js version using Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 115. This version of Node.js requires Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 127. Please try re-compiling or re-installing Oct 11 18:25:11 volumio volumio[2905]: the module (for instance, using `npm rebuild` or `npm install`). Oct 11 18:25:11 volumio volumio[2905]: error: Stack trace: Error: The module '/volumio/node_modules/mdns/build/Release/dns_sd_bindings.node' Oct 11 18:25:11 volumio volumio[2905]: was compiled against a different Node.js version using Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 115. This version of Node.js requires Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 127. Please try re-compiling or re-installing Oct 11 18:25:11 volumio volumio[2905]: the module (for instance, using `npm rebuild` or `npm install`). Oct 11 18:25:11 volumio volumio[2905]: at Module._extensions..node (node:internal/modules/cjs/loader:1717:18) Oct 11 18:25:11 volumio volumio[2905]: at Module.load (node:internal/modules/cjs/loader:1317:32) Oct 11 18:25:11 volumio volumio[2905]: at Module._load (node:internal/modules/cjs/loader:1127:12) Oct 11 18:25:11 volumio volumio[2905]: at TracingChannel.traceSync (node:diagnostics_channel:315:14) Oct 11 18:25:11 volumio volumio[2905]: at wrapModuleLoad (node:internal/modules/cjs/loader:217:24) Oct 11 18:25:11 volumio volumio[2905]: at Module.require (node:internal/modules/cjs/loader:1339:12) Oct 11 18:25:11 volumio volumio[2905]: at require (node:internal/modules/helpers:135:16) Oct 11 18:25:11 volumio volumio[2905]: at Object. (/volumio/node_modules/mdns/lib/dns_sd.js:24:20) Oct 11 18:25:11 volumio volumio[2905]: at Module._compile (node:internal/modules/cjs/loader:1546:14) Oct 11 18:25:11 volumio volumio[2905]: at Module._extensions..js (node:internal/modules/cjs/loader:1691:10) Oct 11 18:25:11 volumio volumio[2905]: error: !!!! WARNING !!!! Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "outputs"... Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "albumart"... Oct 11 18:25:11 volumio volumio[2905]: info: Plugin example_plugin is not enabled Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "inputs"... Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "updater_comm"... Oct 11 18:25:11 volumio volumio[2905]: error: !!!! WARNING !!!! Oct 11 18:25:11 volumio volumio[2905]: error: The plugin system_controller/updater_comm failed to load, setting it to stopped. Error: Error: The module '/volumio/node_modules/inotify/build/Release/inotify.node' Oct 11 18:25:11 volumio volumio[2905]: was compiled against a different Node.js version using Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 115. This version of Node.js requires Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 127. Please try re-compiling or re-installing Oct 11 18:25:11 volumio volumio[2905]: the module (for instance, using `npm rebuild` or `npm install`). Oct 11 18:25:11 volumio volumio[2905]: error: Stack trace: Error: The module '/volumio/node_modules/inotify/build/Release/inotify.node' Oct 11 18:25:11 volumio volumio[2905]: was compiled against a different Node.js version using Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 115. This version of Node.js requires Oct 11 18:25:11 volumio volumio[2905]: NODE_MODULE_VERSION 127. Please try re-compiling or re-installing Oct 11 18:25:11 volumio volumio[2905]: the module (for instance, using `npm rebuild` or `npm install`). Oct 11 18:25:11 volumio volumio[2905]: at Module._extensions..node (node:internal/modules/cjs/loader:1717:18) Oct 11 18:25:11 volumio volumio[2905]: at Module.load (node:internal/modules/cjs/loader:1317:32) Oct 11 18:25:11 volumio volumio[2905]: at Module._load (node:internal/modules/cjs/loader:1127:12) Oct 11 18:25:11 volumio volumio[2905]: at TracingChannel.traceSync (node:diagnostics_channel:315:14) Oct 11 18:25:11 volumio volumio[2905]: at wrapModuleLoad (node:internal/modules/cjs/loader:217:24) Oct 11 18:25:11 volumio volumio[2905]: at Module.require (node:internal/modules/cjs/loader:1339:12) Oct 11 18:25:11 volumio volumio[2905]: at require (node:internal/modules/helpers:135:16) Oct 11 18:25:11 volumio volumio[2905]: at bindings (/volumio/node_modules/bindings/bindings.js:112:48) Oct 11 18:25:11 volumio volumio[2905]: at Object. (/volumio/node_modules/inotify/inotify.js:1:37) Oct 11 18:25:11 volumio volumio[2905]: at Module._compile (node:internal/modules/cjs/loader:1546:14) Oct 11 18:25:11 volumio volumio[2905]: error: !!!! WARNING !!!! Oct 11 18:25:11 volumio volumio[2905]: info: Plugin mpdemulation is not enabled Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "rest_api"... Oct 11 18:25:11 volumio volumio[2905]: info: Loading plugin "websocket"... Oct 11 18:25:11 volumio volumio[2905]: info: Starting Socket.io Server version 1.7.4 Oct 11 18:25:11 volumio volumio[2905]: info: Loading i18n strings for locale en Oct 11 18:25:11 volumio volumio[2905]: Updating browse sources language Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 18:25:11 volumio volumio[2954]: Forking 3 albumart workers Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::initPlayerControls Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: Express server listening on port 3000 Oct 11 18:25:11 volumio volumio[2905]: [Metrics] WebUI: 2s 993.69ms Oct 11 18:25:11 volumio volumio[2905]: (node:2905) [DEP0040] DeprecationWarning: The `punycode` module is deprecated. Please use a userland alternative instead. Oct 11 18:25:11 volumio volumio[2905]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 11 18:25:11 volumio volumio[2905]: info: CoreStateMachine::resetVolumioState Oct 11 18:25:11 volumio volumio[2905]: info: CoreStateMachine::getcurrentVolume Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 18:25:11 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:25:11 volumio volumio[2905]: info: Cannot read play queue from file Oct 11 18:25:12 volumio volumio[2905]: info: VolumeController:: Volume=86 Mute =false Oct 11 18:25:12 volumio volumio[2905]: info: CoreStateMachine::pushState Oct 11 18:25:12 volumio volumio[2905]: info: CorePlayQueue::getTrack 0 Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::volumioPushState Oct 11 18:25:12 volumio volumio[2905]: info: CoreStateMachine::updateTrackBlock Oct 11 18:25:12 volumio volumio[2905]: info: CorePlayQueue::getTrackBlock Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 18:25:12 volumio volumio[2905]: info: Setting Device type: Raspberry PI Oct 11 18:25:12 volumio volumio[2905]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 11 18:25:12 volumio volumio[2905]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Oct 11 18:25:12 volumio volumio[2905]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 11 18:25:12 volumio volumio[2905]: info: VolumeController:: Volume=86 Mute =false Oct 11 18:25:12 volumio volumio[2905]: info: CoreStateMachine::pushState Oct 11 18:25:12 volumio volumio[2905]: info: CorePlayQueue::getTrack 0 Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::volumioPushState Oct 11 18:25:12 volumio volumio[2905]: info: CorePlayQueue::getTrack 0 Oct 11 18:25:12 volumio volumio[2905]: info: Completed loading Core Plugins Oct 11 18:25:12 volumio volumio[2905]: info: Preparing to generate the ALSA configuration file Oct 11 18:25:12 volumio volumio[2905]: info: Asound.conf file written Oct 11 18:25:12 volumio sudo[2994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 11 18:25:12 volumio sudo[2994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:12 volumio sudo[2994]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:12 volumio volumio[2905]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Oct 11 18:25:12 volumio volumio[2905]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 11 18:25:12 volumio volumio[2905]: No state is present for card b1 Oct 11 18:25:12 volumio volumio[2905]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Oct 11 18:25:12 volumio volumio[2905]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 11 18:25:12 volumio volumio[2905]: /usr/share/alsa/init/bcm2835:14: control element not found Oct 11 18:25:12 volumio volumio[2905]: No state is present for card b1 Oct 11 18:25:12 volumio volumio[2905]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Oct 11 18:25:12 volumio volumio[2905]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 11 18:25:12 volumio volumio[2905]: No state is present for card Headphones Oct 11 18:25:12 volumio volumio[2905]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Oct 11 18:25:12 volumio volumio[2905]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 11 18:25:12 volumio volumio[2905]: /usr/share/alsa/init/bcm2835:20: control element not found Oct 11 18:25:12 volumio volumio[2905]: No state is present for card Headphones Oct 11 18:25:12 volumio volumio[2905]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Oct 11 18:25:12 volumio volumio[2905]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 11 18:25:12 volumio volumio[2905]: No state is present for card vc4hdmi0 Oct 11 18:25:12 volumio volumio[2905]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Oct 11 18:25:12 volumio volumio[2905]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 11 18:25:12 volumio volumio[2905]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 11 18:25:12 volumio volumio[2905]: Hardware is initialized using a generic method Oct 11 18:25:12 volumio volumio[2905]: No state is present for card vc4hdmi0 Oct 11 18:25:12 volumio volumio[2905]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Oct 11 18:25:12 volumio volumio[2905]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 11 18:25:12 volumio volumio[2905]: No state is present for card vc4hdmi1 Oct 11 18:25:12 volumio volumio[2905]: alsa-lib parser.c:2783:(load_toplevel_config) Unable to find the top-level configuration file '/usr/share/alsa/ucm2/ucm.conf'. Oct 11 18:25:12 volumio volumio[2905]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 11 18:25:12 volumio volumio[2905]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 11 18:25:12 volumio volumio[2905]: Hardware is initialized using a generic method Oct 11 18:25:12 volumio volumio[2905]: No state is present for card vc4hdmi1 Oct 11 18:25:12 volumio volumio[2905]: info: Output device has changed, restarting MPD Oct 11 18:25:12 volumio volumio[2905]: info: Output device has changed, restarting Shairport Sync Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:25:12 volumio sudo[2999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 18:25:12 volumio sudo[2999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:12 volumio sudo[3001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 18:25:12 volumio volumio[2905]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 18:25:12 volumio volumio[2905]: info: ___________ START PLUGINS ___________ Oct 11 18:25:12 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:12 volumio sudo[2999]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:12 volumio volumio[2905]: info: Setting Hotspot Unique name on first start: Volumio-9047C Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::Reload Ui Oct 11 18:25:12 volumio volumio[2905]: info: ControllerMpd::onStart: Initializing MPD Oct 11 18:25:12 volumio volumio[2905]: info: Creating MPD Configuration file Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:25:12 volumio sudo[3010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 18:25:12 volumio sudo[3010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:12 volumio volumio[2905]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 18:25:12 volumio volumio[2905]: info: [1728671112533] CoreMusicLibrary::Adding element Last_100 Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 18:25:12 volumio volumio[2905]: info: Volumio Calling Home Oct 11 18:25:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 11 18:25:12 volumio sudo[3008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 18:25:12 volumio sudo[3008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:12 volumio sudo[3008]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:12 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 11 18:25:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 11 18:25:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 11 18:25:12 volumio volumio[2961]: Starting albumart workers Oct 11 18:25:12 volumio volumio[2905]: info: MPD Permissions set Oct 11 18:25:12 volumio volumio[2905]: info: MPD Permissions set Oct 11 18:25:12 volumio volumio[2905]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 11 18:25:12 volumio volumio[2905]: info: Volumio called home Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2963]: Starting albumart workers Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 18:25:12 volumio volumio[2905]: info: Starting Shairport Sync Oct 11 18:25:12 volumio volumio[2905]: info: Starting Shairport Sync Oct 11 18:25:13 volumio volumio[2905]: info: Starting Shairport Sync Oct 11 18:25:13 volumio sudo[3033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 18:25:13 volumio sudo[3033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:13 volumio sudo[3035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 18:25:13 volumio sudo[3035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:13 volumio sudo[3038]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 18:25:13 volumio sudo[3038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 18:25:13 volumio volumio[2962]: Starting albumart workers Oct 11 18:25:13 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 11 18:25:13 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 11 18:25:13 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 18:25:13 volumio systemd[1]: shairport-sync.service: Consumed 2.933s CPU time. Oct 11 18:25:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 18:25:13 volumio sudo[3035]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:13 volumio sudo[3033]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:13 volumio sudo[3038]: pam_unix(sudo:session): session closed for user root Oct 11 18:25:13 volumio volumio[2905]: info: CoreCommandRouter::volumioGetState Oct 11 18:25:13 volumio volumio[2905]: info: CorePlayQueue::getTrack 0 Oct 11 18:25:13 volumio volumio[2905]: info: Shairport-Sync Started Oct 11 18:25:13 volumio volumio[2905]: Error adding Membership: Error: addMembership EINVAL Oct 11 18:25:13 volumio volumio[2905]: info: Shairport-Sync Started Oct 11 18:25:13 volumio volumio[2905]: info: Shairport-Sync Started Oct 11 18:25:13 volumio volumio[2905]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 18:25:13 volumio volumio[2905]: TypeError: f.split(...)[0].capitalize is not a function Oct 11 18:25:13 volumio volumio[2905]: at /volumio/app/plugins/miscellanea/appearance/index.js:191:56 Oct 11 18:25:13 volumio volumio[2905]: at Array.forEach () Oct 11 18:25:13 volumio volumio[2905]: at /volumio/app/plugins/miscellanea/appearance/index.js:189:11 Oct 11 18:25:13 volumio volumio[2905]: at /volumio/node_modules/graceful-fs/graceful-fs.js:228:16 Oct 11 18:25:13 volumio volumio[2905]: at FSReqCallback.oncomplete (node:fs:187:23) Oct 11 18:25:13 volumio volumio[2905]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 18:25:13 volumio sudo[3089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2024-10-11 18:24' Oct 11 18:25:13 volumio sudo[3089]: 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="03b0f4bb8a730f6731d293dbff8644fba78fa33f" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="ae570ce1d1f1282a3461dbb6257d6e6428153f98" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 2 13:11:49 UTC 2024" VOLUMIO_VERSION="4.000" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="469e7786eada6149b909fa9baded4c52"